builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0298 starttime: 1483549345.09 results: success (0) buildid: 20170104063707 builduid: 792fb026949d43a5a5c0948120355c47 revision: 6fc25451e804c71e053d15091d8ba1113395d4cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.090529) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.090993) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.091271) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.117378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.117710) ========= 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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 --2017-01-04 09:02:25-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-01-04 09:02:25 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160980 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.383161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.383472) ========= 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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.024888 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:25.433013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 09:02:25.433389) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6fc25451e804c71e053d15091d8ba1113395d4cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6fc25451e804c71e053d15091d8ba1113395d4cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 2017-01-04 09:02:25,527 truncating revision to first 12 chars 2017-01-04 09:02:25,527 Setting DEBUG logging. 2017-01-04 09:02:25,528 attempt 1/10 2017-01-04 09:02:25,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6fc25451e804?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:02:27,471 unpacking tar archive at: mozilla-aurora-6fc25451e804/testing/mozharness/ program finished with exit code 0 elapsedTime=2.218101 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 09:02:28.089863) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:28.090209) ========= script_repo_revision: 6fc25451e804c71e053d15091d8ba1113395d4cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:28.093689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:28.093963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:02:28.112428) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-04 09:02:28.112729) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:02:28 INFO - MultiFileLogger online at 20170104 09:02:28 in /builds/slave/test 09:02:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:02:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:28 INFO - {'append_to_log': False, 09:02:28 INFO - 'base_work_dir': '/builds/slave/test', 09:02:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:02:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:28 INFO - 'branch': 'Mozilla-Aurora', 09:02:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:28 INFO - 'config_files': ('talos/mac_config.py',), 09:02:28 INFO - 'default_actions': ('clobber', 09:02:28 INFO - 'read-buildbot-config', 09:02:28 INFO - 'download-and-extract', 09:02:28 INFO - 'populate-webroot', 09:02:28 INFO - 'create-virtualenv', 09:02:28 INFO - 'install', 09:02:28 INFO - 'run-tests'), 09:02:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:28 INFO - 'download_minidump_stackwalk': True, 09:02:28 INFO - 'download_symbols': 'ondemand', 09:02:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:28 INFO - 'installer_path': 'installer.exe', 09:02:28 INFO - 'log_level': 'info', 09:02:28 INFO - 'log_name': 'talos', 09:02:28 INFO - 'log_to_console': True, 09:02:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:02:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:02:28 INFO - 'opt_config_files': (), 09:02:28 INFO - 'pip_index': False, 09:02:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:28 INFO - 'cmd': ('bash', 09:02:28 INFO - '-c', 09:02:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:28 INFO - 'enabled': True, 09:02:28 INFO - 'halt_on_failure': False, 09:02:28 INFO - 'name': 'check_screen_resolution'},), 09:02:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:28 INFO - 'cmd': ('bash', 09:02:28 INFO - '-c', 09:02:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:28 INFO - 'enabled': True, 09:02:28 INFO - 'halt_on_failure': False, 09:02:28 INFO - 'name': 'check_screen_resolution'},), 09:02:28 INFO - 'run_cmd_checks_enabled': True, 09:02:28 INFO - 'sps_profile': False, 09:02:28 INFO - 'sps_profile_interval': 0, 09:02:28 INFO - 'suite': 'dromaeojs-e10s', 09:02:28 INFO - 'system_bits': '32', 09:02:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:28 INFO - 'title': 't-yosemite-r7-0298', 09:02:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:28 INFO - 'use_talos_json': True, 09:02:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:28 INFO - 'work_dir': 'build'} 09:02:28 INFO - [mozharness: 2017-01-04 17:02:28.310594Z] Running clobber step. 09:02:28 INFO - Running pre-action listener: _resource_record_pre_action 09:02:28 INFO - Running main action method: clobber 09:02:28 INFO - rmtree: /builds/slave/test/build 09:02:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:30 INFO - Running post-action listener: _resource_record_post_action 09:02:30 INFO - [mozharness: 2017-01-04 17:02:30.685404Z] Finished clobber step (success) 09:02:30 INFO - [mozharness: 2017-01-04 17:02:30.685548Z] Running read-buildbot-config step. 09:02:30 INFO - Running pre-action listener: _resource_record_pre_action 09:02:30 INFO - Running main action method: read_buildbot_config 09:02:30 INFO - Using buildbot properties: 09:02:30 INFO - { 09:02:30 INFO - "project": "", 09:02:30 INFO - "product": "firefox", 09:02:30 INFO - "script_repo_revision": "production", 09:02:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:02:30 INFO - "repository": "", 09:02:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:02:30 INFO - "buildid": "20170104063707", 09:02:30 INFO - "pgo_build": "False", 09:02:30 INFO - "basedir": "/builds/slave/test", 09:02:30 INFO - "buildnumber": 21, 09:02:30 INFO - "slavename": "t-yosemite-r7-0298", 09:02:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:02:30 INFO - "platform": "yosemite_r7", 09:02:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:02:30 INFO - "branch": "mozilla-aurora", 09:02:30 INFO - "repo_path": "releases/mozilla-aurora", 09:02:30 INFO - "revision": "6fc25451e804c71e053d15091d8ba1113395d4cf", 09:02:30 INFO - "stage_platform": "macosx64", 09:02:30 INFO - "builduid": "792fb026949d43a5a5c0948120355c47", 09:02:30 INFO - "slavebuilddir": "test" 09:02:30 INFO - } 09:02:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:02:30 INFO - Running post-action listener: _resource_record_post_action 09:02:30 INFO - [mozharness: 2017-01-04 17:02:30.688753Z] Finished read-buildbot-config step (success) 09:02:30 INFO - [mozharness: 2017-01-04 17:02:30.688869Z] Running download-and-extract step. 09:02:30 INFO - Running pre-action listener: _resource_record_pre_action 09:02:30 INFO - Running main action method: download_and_extract 09:02:30 INFO - mkdir: /builds/slave/test/build/tests 09:02: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')]} 09:02:30 INFO - trying https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:02:30 INFO - Downloading https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:02:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:02:31 INFO - Downloaded 1199 bytes. 09:02:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:02:31 INFO - Using the following test package requirements: 09:02:31 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:02:31 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:02:31 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:02:31 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'jsshell-mac.zip'], 09:02:31 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:02:31 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:02:31 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:02:31 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:02:31 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:02:31 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:02:31 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:02:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:02:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:02:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:02:31 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:02:32 INFO - Http code: 200 09:02:32 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 09:02:32 INFO - x-amz-cf-id: jikSZyTFj8R7rgffMQSq8Rhu8l_QaHm1-s6fsyZfbf7lcPrtFl3kmA== 09:02:32 INFO - x-amz-version-id: yMkYaeGcpvsCdf9f9VkNZa3LVxtCwOTq 09:02:32 INFO - Expected file size: 16269875 09:02:32 INFO - Obtained file size: 16269875 09:02:39 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:02:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:02:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:02:39 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:02:39 INFO - Http code: 200 09:02:39 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 09:02:39 INFO - x-amz-cf-id: pAR23zYTuo2cTqfapqt_YO3L2iTYCyCnerXdgnWBH3IKveav7tez1A== 09:02:39 INFO - x-amz-version-id: yMkYaeGcpvsCdf9f9VkNZa3LVxtCwOTq 09:02:40 INFO - Expected file size: 16269875 09:02:40 INFO - Obtained file size: 16269875 09:02:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:02:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:02:46 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:02:47 INFO - Http code: 200 09:02:47 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:02:47 INFO - x-amz-cf-id: hTMjPvPggdp1VM7VyTjUHzQA0LrWlMM3u4-kj42tCpwc49WCUALtuw== 09:02:47 INFO - x-amz-version-id: Ehs5OV9guIJ2WCX.nAvYkUikYCjeOMAl 09:02:47 INFO - Expected file size: 14409038 09:02:47 INFO - Obtained file size: 14409038 09:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:47 INFO - trying https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:02:47 INFO - Downloading https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:02:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:02:50 INFO - Downloaded 109834910 bytes. 09:02:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:02:50 INFO - mkdir: /builds/slave/test/properties 09:02:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:50 INFO - Contents: 09:02:50 INFO - build_url:https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:02:51 INFO - Running post-action listener: _resource_record_post_action 09:02:51 INFO - Running post-action listener: set_extra_try_arguments 09:02:51 INFO - [mozharness: 2017-01-04 17:02:51.257805Z] Finished download-and-extract step (success) 09:02:51 INFO - [mozharness: 2017-01-04 17:02:51.257921Z] Running populate-webroot step. 09:02:51 INFO - Running pre-action listener: _resource_record_pre_action 09:02:51 INFO - Running main action method: populate_webroot 09:02:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:51 INFO - '--tppagecycles', 09:02:51 INFO - '7'], 09:02:51 INFO - 'tests': ['tsvgm']}}, 09:02:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['damp', 'tps']}, 09:02:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'tests': ['damp', 'tps']}, 09:02:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['dromaeo_dom']}, 09:02:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['basic_compositor_video']}, 09:02:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['a11yr', 09:02:51 INFO - 'ts_paint', 09:02:51 INFO - 'tpaint', 09:02:51 INFO - 'sessionrestore', 09:02:51 INFO - 'sessionrestore_no_auto_restore', 09:02:51 INFO - 'tabpaint']}, 09:02:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['tsvgx', 09:02:51 INFO - 'tsvgr_opacity', 09:02:51 INFO - 'tart', 09:02:51 INFO - 'tscrollx', 09:02:51 INFO - 'cart']}, 09:02:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:51 INFO - 'tsvgr_opacity', 09:02:51 INFO - 'tart', 09:02:51 INFO - 'tscrollx', 09:02:51 INFO - 'cart']}, 09:02:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'talos_options': ['--disable-e10s'], 09:02:51 INFO - 'tests': ['tp5o']}, 09:02:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'tests': ['tp5o']}, 09:02:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'talos_options': ['--disable-e10s', 09:02:51 INFO - '--xperf_path', 09:02:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:51 INFO - 'tests': ['tp5n']}, 09:02:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:51 INFO - 'talos_options': ['--xperf_path', 09:02:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:51 INFO - 'tests': ['tp5n']}}, 09:02:51 INFO - 'talos.zip': {'path': '', 09:02:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:51 INFO - Running post-action listener: _resource_record_post_action 09:02:51 INFO - [mozharness: 2017-01-04 17:02:51.277148Z] Finished populate-webroot step (success) 09:02:51 INFO - [mozharness: 2017-01-04 17:02:51.277251Z] Running create-virtualenv step. 09:02:51 INFO - Running pre-action listener: _resource_record_pre_action 09:02:51 INFO - Running main action method: create_virtualenv 09:02:51 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:51 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 09:02:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:51 INFO - Using real prefix '/tools/python27' 09:02:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:52 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:54 INFO - Installing pip.................done. 09:02:54 INFO - Return code: 0 09:02:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:55 INFO - Reading from file tmpfile_stdout 09:02:55 INFO - Output received: 09:02:55 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) 09:02:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:55 INFO - 'HOME': '/Users/cltbld', 09:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:55 INFO - 'LOGNAME': 'cltbld', 09:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:55 INFO - 'MOZ_NO_REMOTE': '1', 09:02:55 INFO - 'NO_EM_RESTART': '1', 09:02:55 INFO - 'PAGER': '/bin/cat', 09:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:55 INFO - 'PWD': '/builds/slave/test', 09:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:55 INFO - 'SHELL': '/bin/bash', 09:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:02:55 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:55 INFO - 'USER': 'cltbld', 09:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:55 INFO - 'XPC_FLAGS': '0x0', 09:02:55 INFO - 'XPC_SERVICE_NAME': '0', 09:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:55 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 09:02:55 INFO - Cleaning up... 09:02:55 INFO - Return code: 0 09:02:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:55 INFO - 'HOME': '/Users/cltbld', 09:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:55 INFO - 'LOGNAME': 'cltbld', 09:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:55 INFO - 'MOZ_NO_REMOTE': '1', 09:02:55 INFO - 'NO_EM_RESTART': '1', 09:02:55 INFO - 'PAGER': '/bin/cat', 09:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:55 INFO - 'PWD': '/builds/slave/test', 09:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:55 INFO - 'SHELL': '/bin/bash', 09:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:02:55 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:55 INFO - 'USER': 'cltbld', 09:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:55 INFO - 'XPC_FLAGS': '0x0', 09:02:55 INFO - 'XPC_SERVICE_NAME': '0', 09:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:55 INFO - Downloading/unpacking psutil>=3.1.1 09:02:55 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 09:02:55 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 09:02:55 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 09:02:55 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 09:02:55 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 09:02:55 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 09:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:59 INFO - Installing collected packages: psutil 09:02:59 INFO - Running setup.py install for psutil 09:02:59 INFO - building 'psutil._psutil_osx' extension 09:02:59 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 09:02:59 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 09:03:00 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 09:03:00 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 09:03:00 INFO - building 'psutil._psutil_posix' extension 09:03:00 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 09:03:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:03:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:03:00 INFO - ^ 09:03:00 INFO - 1 warning generated. 09:03:00 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 09:03:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:00 INFO - Successfully installed psutil 09:03:00 INFO - Cleaning up... 09:03:00 INFO - Return code: 0 09:03:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:03: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')]} 09:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03: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')]} 09:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:00 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:00 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 09:03:00 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 09:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:00 INFO - 'HOME': '/Users/cltbld', 09:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:00 INFO - 'LOGNAME': 'cltbld', 09:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:00 INFO - 'MOZ_NO_REMOTE': '1', 09:03:00 INFO - 'NO_EM_RESTART': '1', 09:03:00 INFO - 'PAGER': '/bin/cat', 09:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:00 INFO - 'PWD': '/builds/slave/test', 09:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:00 INFO - 'SHELL': '/bin/bash', 09:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:00 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:00 INFO - 'USER': 'cltbld', 09:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:00 INFO - 'XPC_FLAGS': '0x0', 09:03:00 INFO - 'XPC_SERVICE_NAME': '0', 09:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:03:00 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 09:03:00 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 09:03:00 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 09:03:00 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 09:03:00 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 09:03:00 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 09:03:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:03 INFO - Installing collected packages: mozsystemmonitor 09:03:03 INFO - Running setup.py install for mozsystemmonitor 09:03:03 INFO - Successfully installed mozsystemmonitor 09:03:03 INFO - Cleaning up... 09:03:03 INFO - Return code: 0 09:03:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:03 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')]} 09:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:03 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')]} 09:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:03 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:03 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 09:03:03 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 09:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:03 INFO - 'HOME': '/Users/cltbld', 09:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:03 INFO - 'LOGNAME': 'cltbld', 09:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:03 INFO - 'MOZ_NO_REMOTE': '1', 09:03:03 INFO - 'NO_EM_RESTART': '1', 09:03:03 INFO - 'PAGER': '/bin/cat', 09:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:03 INFO - 'PWD': '/builds/slave/test', 09:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:03 INFO - 'SHELL': '/bin/bash', 09:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:03 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:03 INFO - 'USER': 'cltbld', 09:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:03 INFO - 'XPC_FLAGS': '0x0', 09:03:03 INFO - 'XPC_SERVICE_NAME': '0', 09:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:04 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 09:03:04 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 09:03:04 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 09:03:04 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 09:03:04 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 09:03:04 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 09:03:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:07 INFO - warning: no files found matching '*.txt' 09:03:07 INFO - no previously-included directories found matching 'build' 09:03:07 INFO - no previously-included directories found matching 'dist' 09:03:07 INFO - no previously-included directories found matching '.git*' 09:03:07 INFO - Installing collected packages: jsonschema, functools32 09:03:07 INFO - Running setup.py install for functools32 09:03:07 INFO - warning: no files found matching '*.txt' 09:03:07 INFO - no previously-included directories found matching 'build' 09:03:07 INFO - no previously-included directories found matching 'dist' 09:03:07 INFO - no previously-included directories found matching '.git*' 09:03:07 INFO - Successfully installed jsonschema functools32 09:03:07 INFO - Cleaning up... 09:03:07 INFO - Return code: 0 09:03:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:07 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:07 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 09:03:07 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 09:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:07 INFO - 'HOME': '/Users/cltbld', 09:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:07 INFO - 'LOGNAME': 'cltbld', 09:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:07 INFO - 'MOZ_NO_REMOTE': '1', 09:03:07 INFO - 'NO_EM_RESTART': '1', 09:03:07 INFO - 'PAGER': '/bin/cat', 09:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:07 INFO - 'PWD': '/builds/slave/test', 09:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:07 INFO - 'SHELL': '/bin/bash', 09:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:07 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:07 INFO - 'USER': 'cltbld', 09:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:07 INFO - 'XPC_FLAGS': '0x0', 09:03:07 INFO - 'XPC_SERVICE_NAME': '0', 09:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:03:07 INFO - Cleaning up... 09:03:07 INFO - Return code: 0 09:03:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:07 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:07 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 09:03:07 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 09:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:07 INFO - 'HOME': '/Users/cltbld', 09:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:07 INFO - 'LOGNAME': 'cltbld', 09:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:07 INFO - 'MOZ_NO_REMOTE': '1', 09:03:07 INFO - 'NO_EM_RESTART': '1', 09:03:07 INFO - 'PAGER': '/bin/cat', 09:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:07 INFO - 'PWD': '/builds/slave/test', 09:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:07 INFO - 'SHELL': '/bin/bash', 09:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:07 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:07 INFO - 'USER': 'cltbld', 09:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:07 INFO - 'XPC_FLAGS': '0x0', 09:03:07 INFO - 'XPC_SERVICE_NAME': '0', 09:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03:10 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 09:03:10 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 09:03:10 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 09:03:10 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 09:03:10 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 09:03:10 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 09:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:11 INFO - Downloading docopt-0.6.1.tar.gz 09:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:12 INFO - Running setup.py install for blobuploader 09:03:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:12 INFO - Running setup.py install for requests 09:03:12 INFO - Running setup.py install for docopt 09:03:12 INFO - Successfully installed blobuploader requests docopt 09:03:12 INFO - Cleaning up... 09:03:12 INFO - Return code: 0 09:03:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:12 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:12 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 09:03:12 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 09:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:12 INFO - 'HOME': '/Users/cltbld', 09:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:12 INFO - 'LOGNAME': 'cltbld', 09:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:12 INFO - 'MOZ_NO_REMOTE': '1', 09:03:12 INFO - 'NO_EM_RESTART': '1', 09:03:12 INFO - 'PAGER': '/bin/cat', 09:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:12 INFO - 'PWD': '/builds/slave/test', 09:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:12 INFO - 'SHELL': '/bin/bash', 09:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:12 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:12 INFO - 'USER': 'cltbld', 09:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:12 INFO - 'XPC_FLAGS': '0x0', 09:03:12 INFO - 'XPC_SERVICE_NAME': '0', 09:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-tv2Dlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-zbsI0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-fN6HwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-BJovzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-luxTPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rbnTjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_vU5yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:13 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-74Ww7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-H3QKf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-TFZu02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-55Gab_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-exbSEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-n2WkCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-xgQIO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_g8D0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-i1Mh3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:14 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-lwGZm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:14 INFO - Running setup.py install for manifestparser 09:03:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:15 INFO - Running setup.py install for mozcrash 09:03:15 INFO - Running setup.py install for mozdebug 09:03:15 INFO - Running setup.py install for mozdevice 09:03:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:15 INFO - Running setup.py install for mozfile 09:03:15 INFO - Running setup.py install for mozhttpd 09:03:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:15 INFO - Running setup.py install for mozinfo 09:03:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Running setup.py install for mozInstall 09:03:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Running setup.py install for mozleak 09:03:16 INFO - Running setup.py install for mozlog 09:03:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Running setup.py install for moznetwork 09:03:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Running setup.py install for mozprocess 09:03:16 INFO - Running setup.py install for mozprofile 09:03:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:16 INFO - Running setup.py install for mozrunner 09:03:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:17 INFO - Running setup.py install for mozscreenshot 09:03:17 INFO - Running setup.py install for moztest 09:03:17 INFO - Running setup.py install for mozversion 09:03:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:17 INFO - Cleaning up... 09:03:17 INFO - Return code: 0 09:03:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:17 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 09:03:17 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 09:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:17 INFO - 'HOME': '/Users/cltbld', 09:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:17 INFO - 'LOGNAME': 'cltbld', 09:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:17 INFO - 'MOZ_NO_REMOTE': '1', 09:03:17 INFO - 'NO_EM_RESTART': '1', 09:03:17 INFO - 'PAGER': '/bin/cat', 09:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:17 INFO - 'PWD': '/builds/slave/test', 09:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:17 INFO - 'SHELL': '/bin/bash', 09:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:17 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:17 INFO - 'USER': 'cltbld', 09:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:17 INFO - 'XPC_FLAGS': '0x0', 09:03:17 INFO - 'XPC_SERVICE_NAME': '0', 09:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:17 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-tG3BDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:17 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)) 09:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:17 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-dvgFcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-vQGvVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-gqLtLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-PtdANs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-SVPa8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-CxWBHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ovlfYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-2j4pDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ClaZAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:18 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)) 09:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:18 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RYqrV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:19 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-mXGBVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:19 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-usXzIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:19 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Kx4FzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rq01PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:19 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-eSKpmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:19 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-MKL2df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:19 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)) 09:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:19 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)) 09:03:19 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)) 09:03:19 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)) 09:03:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:19 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 09:03:19 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 09:03:19 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 09:03:19 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 09:03:19 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 09:03:19 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 09:03:22 INFO - Downloading blessings-1.6.tar.gz 09:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:22 INFO - Installing collected packages: blessings 09:03:22 INFO - Running setup.py install for blessings 09:03:22 INFO - Successfully installed blessings 09:03:22 INFO - Cleaning up... 09:03:23 INFO - Return code: 0 09:03:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:23 INFO - Reading from file tmpfile_stdout 09:03:23 INFO - Current package versions: 09:03:23 INFO - blessings == 1.6 09:03:23 INFO - blobuploader == 1.2.4 09:03:23 INFO - docopt == 0.6.1 09:03:23 INFO - functools32 == 3.2.3-2 09:03:23 INFO - jsonschema == 2.5.1 09:03:23 INFO - manifestparser == 1.1 09:03:23 INFO - mozInstall == 1.12 09:03:23 INFO - mozcrash == 1.0 09:03:23 INFO - mozdebug == 0.1 09:03:23 INFO - mozdevice == 0.48 09:03:23 INFO - mozfile == 1.2 09:03:23 INFO - mozhttpd == 0.7 09:03:23 INFO - mozinfo == 0.9 09:03:23 INFO - mozleak == 0.1 09:03:23 INFO - mozlog == 3.4 09:03:23 INFO - moznetwork == 0.27 09:03:23 INFO - mozprocess == 0.23 09:03:23 INFO - mozprofile == 0.28 09:03:23 INFO - mozrunner == 6.13 09:03:23 INFO - mozscreenshot == 0.1 09:03:23 INFO - mozsystemmonitor == 0.3 09:03:23 INFO - moztest == 0.8 09:03:23 INFO - mozversion == 1.4 09:03:23 INFO - psutil == 3.1.1 09:03:23 INFO - requests == 1.2.3 09:03:23 INFO - wsgiref == 0.1.2 09:03:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:23 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')]} 09:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:23 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')]} 09:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:23 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:23 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 09:03:23 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 09:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:23 INFO - 'HOME': '/Users/cltbld', 09:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:23 INFO - 'LOGNAME': 'cltbld', 09:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:23 INFO - 'MOZ_NO_REMOTE': '1', 09:03:23 INFO - 'NO_EM_RESTART': '1', 09:03:23 INFO - 'PAGER': '/bin/cat', 09:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:23 INFO - 'PWD': '/builds/slave/test', 09:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:23 INFO - 'SHELL': '/bin/bash', 09:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:23 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:23 INFO - 'USER': 'cltbld', 09:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:23 INFO - 'XPC_FLAGS': '0x0', 09:03:23 INFO - 'XPC_SERVICE_NAME': '0', 09:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 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)) 09:03:23 INFO - Cleaning up... 09:03:23 INFO - Return code: 0 09:03:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:23 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')]} 09:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:23 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')]} 09:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:23 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 0x10cb99490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2e1484b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:03:23 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 09:03:23 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 09:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:23 INFO - 'HOME': '/Users/cltbld', 09:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:23 INFO - 'LOGNAME': 'cltbld', 09:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:23 INFO - 'MOZ_NO_REMOTE': '1', 09:03:23 INFO - 'NO_EM_RESTART': '1', 09:03:23 INFO - 'PAGER': '/bin/cat', 09:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:23 INFO - 'PWD': '/builds/slave/test', 09:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:23 INFO - 'SHELL': '/bin/bash', 09:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:23 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:23 INFO - 'USER': 'cltbld', 09:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:23 INFO - 'XPC_FLAGS': '0x0', 09:03:23 INFO - 'XPC_SERVICE_NAME': '0', 09:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:24 INFO - Cleaning up... 09:03:24 INFO - Return code: 0 09:03:24 INFO - Running post-action listener: _resource_record_post_action 09:03:24 INFO - Running post-action listener: _start_resource_monitoring 09:03:24 INFO - Starting resource monitoring. 09:03:24 INFO - [mozharness: 2017-01-04 17:03:24.262111Z] Finished create-virtualenv step (success) 09:03:24 INFO - [mozharness: 2017-01-04 17:03:24.262912Z] Running install step. 09:03:24 INFO - Running pre-action listener: _resource_record_pre_action 09:03:24 INFO - Running main action method: install 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:24 INFO - Reading from file tmpfile_stdout 09:03:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:24 INFO - Reading from file tmpfile_stdout 09:03:24 INFO - Output received: 09:03:24 INFO - Usage: mozinstall [options] installer 09:03:24 INFO - Options: 09:03:24 INFO - -h, --help show this help message and exit 09:03:24 INFO - -d DEST, --destination=DEST 09:03:24 INFO - Directory to install application into. [default: 09:03:24 INFO - "/builds/slave/test"] 09:03:24 INFO - --app=APP Application being installed. [default: firefox] 09:03:24 INFO - mkdir: /builds/slave/test/build/application 09:03:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:03:55 INFO - Reading from file tmpfile_stdout 09:03:55 INFO - Output received: 09:03:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:03:55 INFO - Running post-action listener: _resource_record_post_action 09:03:55 INFO - [mozharness: 2017-01-04 17:03:55.656468Z] Finished install step (success) 09:03:55 INFO - [mozharness: 2017-01-04 17:03:55.656637Z] Running run-tests step. 09:03:55 INFO - Running pre-action listener: _resource_record_pre_action 09:03:55 INFO - Running main action method: run_tests 09:03:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:55 INFO - 2017-01-04 09:03:55.671 screenresolution[1622:7586] starting screenresolution argv=screenresolution get 09:03:55 INFO - 2017-01-04 09:03:55.673 screenresolution[1622:7586] Display 0: 1600x1200x32@60 09:03:55 INFO - 2017-01-04 09:03:55.679 screenresolution[1623:7589] starting screenresolution argv=screenresolution list 09:03:55 INFO - Available Modes on Display 0 09:03:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:55 INFO - Intel Iris: 09:03:55 INFO - Chipset Model: Intel Iris 09:03:55 INFO - Type: GPU 09:03:55 INFO - Bus: Built-In 09:03:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:55 INFO - Vendor: Intel (0x8086) 09:03:55 INFO - Device ID: 0x0a2e 09:03:55 INFO - Revision ID: 0x0009 09:03:55 INFO - Displays: 09:03:55 INFO - Display: 09:03:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:55 INFO - Display Serial Number: 1600x1200 60 09:03:55 INFO - Main Display: Yes 09:03:55 INFO - Mirror: Off 09:03:55 INFO - Online: Yes 09:03:55 INFO - Rotation: Supported 09:03:55 INFO - Return code: 0 09:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:55 INFO - Python 2.7.3 09:03:55 INFO - Return code: 0 09:03:55 INFO - grabbing minidump binary from tooltool 09:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:55 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 0x10c9add30>, '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 0x10c813db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:55 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 09:03:55 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 09:03:55 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 09:03:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:56 INFO - Return code: 0 09:03:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 09:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 09:03:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ziEb6Jh8ck/Render', 09:03:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:56 INFO - 'HOME': '/Users/cltbld', 09:03:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:56 INFO - 'LOGNAME': 'cltbld', 09:03:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:56 INFO - 'MOZ_NO_REMOTE': '1', 09:03:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:56 INFO - 'NO_EM_RESTART': '1', 09:03:56 INFO - 'PAGER': '/bin/cat', 09:03:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:56 INFO - 'PWD': '/builds/slave/test', 09:03:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:56 INFO - 'SHELL': '/bin/bash', 09:03:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cvEhvRY5R/Listeners', 09:03:56 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:03:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:56 INFO - 'USER': 'cltbld', 09:03:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:56 INFO - 'XPC_FLAGS': '0x0', 09:03:56 INFO - 'XPC_SERVICE_NAME': '0', 09:03:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 09:03:56 INFO - mozversion application_buildid: 20170104063707 09:03:56 INFO - mozversion application_changeset: 6fc25451e804c71e053d15091d8ba1113395d4cf 09:03:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:03:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:56 INFO - mozversion application_name: Firefox 09:03:56 INFO - mozversion application_remotingname: firefox-dev 09:03:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:03:56 INFO - mozversion application_vendor: Mozilla 09:03:56 INFO - mozversion application_version: 52.0a2 09:03:56 INFO - mozversion platform_buildid: 20170104063707 09:03:56 INFO - mozversion platform_changeset: 6fc25451e804c71e053d15091d8ba1113395d4cf 09:03:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:03:56 INFO - mozversion platform_version: 52.0a2 09:03:56 INFO - using testdate: 1483549436 09:03:56 INFO - actual date: 1483549436 09:03:56 INFO - starting webserver on 'localhost:49241' 09:03:56 INFO - SUITE-START | Running 2 tests 09:03:56 INFO - TEST-START | dromaeo_css 09:03:56 INFO - operating with platform_type : mac_ 09:03:56 INFO - Initialising browser for dromaeo_css test... 09:03:56 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpk2x380/profile http://localhost:49241/getInfo.html) 09:03:59 INFO - PROCESS | 1633 | 2017-01-04 09:03:59.464 plugin-container[1635:7769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 09:03:59 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:59 INFO - PROCESS | 1633 | 2017-01-04 09:03:59.467 plugin-container[1635:7769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:03:59 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:59 INFO - PROCESS | 1633 | 2017-01-04 09:03:59.467 plugin-container[1635:7769] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:59 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 09:03:59 INFO - PROCESS | 1633 | colorDepth:24 09:03:59 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 09:03:59 INFO - PROCESS | 1633 | __metrics 09:03:59 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:04:00 INFO - TEST-INFO | 1633: exit 0 09:04:00 INFO - Browser initialized. 09:04:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:04:00 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpk2x380/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:03 INFO - PROCESS | 1636 | 2017-01-04 09:04:03.003 plugin-container[1637:7896] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 09:04:03 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:03 INFO - PROCESS | 1636 | 2017-01-04 09:04:03.004 plugin-container[1637:7896] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 09:04:03 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:03 INFO - PROCESS | 1636 | 2017-01-04 09:04:03.004 plugin-container[1637:7896] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:12 INFO - PROCESS | 1636 | RSS: Main: 229539840 09:04:12 INFO - PROCESS | 1636 | 09:07:15 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:07:17 INFO - PROCESS | 1636 | RSS: Main: 232583168 09:07:17 INFO - PROCESS | 1636 | 09:10:19 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:10:20 INFO - PROCESS | 1636 | RSS: Main: 243441664 09:10:20 INFO - PROCESS | 1636 | 09:13:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:13:22 INFO - PROCESS | 1636 | RSS: Main: 246358016 09:13:22 INFO - PROCESS | 1636 | 09:16:24 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:16:25 INFO - PROCESS | 1636 | RSS: Main: 245555200 09:16:25 INFO - PROCESS | 1636 | 09:19:28 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:19:28 INFO - PROCESS | 1636 | RSS: Main: 249044992 09:19:28 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:22:31 INFO - PROCESS | 1636 | RSS: Main: 250490880 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | __start_tp_report 09:22:31 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:22:31 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:22:31 INFO - PROCESS | 1636 | |i|pagename|runs| 09:22:31 INFO - PROCESS | 1636 | |0;dojo.html;2647;3308.8841890533827;3345;3349.9615119313016;3370.106921665759;5503.367112782032;5524.08852539331;5545.972270138644;5562.888742225163;5615.775368985246;4300.849470268541;5572.3034620672415;5583.35791383991;5585.301837270341;5608.298962629671;5109.4379618241965;5137.409197942247;5174.793008279665;5180.792768289264;5201.9479805201845;5154.664946778447;5191.247269145972;5198.974005129988;5212.687238765668;5227.79088836446;4929.186684197103;5060.772265248054;5183.611229157809;5196.220566914955;5200.557952574017;3715.256948610289;3797.620237976208;4278.764667943277;5152.484751524855;5162.3030890830005;4101.384792281182;5114.130597798383;5117.820876269313;5129.692218466904;5173.224016397533;5550.750216240279;5605.5515558755205;5628.662280263197;5636.85907852307;5644.717764111819;5816.331121920991;5874.647521148702;5875.471207591295;5942.673152976585;6009.849753756147;6040.395960403968;6045.909311360333;6068.726907289182;6129.570930034899;6176.258848938111;5545;5556.416576259475;5569.275994120779;5598.1322894951345;5600.719964001865;6151.415615516519;6180.042093475518;6205;6242.43818056368;6257.530685198628;5719.170720245625;5772.7113644317615;5857.7656893724625;5885.970570147208;5901.793437229677;6080.604760690541;6093.69531523422;6095.2685677718955;6100.420460056288;6144.815655530341;4932.482089380635;6013.398660133952;6031.366706495754;6032.788852390146;6045.3652366501765;5799.159121927381;5799.594028418054;5842.941570584325;5921.3190483094995;6032.728527216286;4691.225947718656;4719.575238228507;4744.501827308152;4751.239801631722;4756.334113224151;4254.574542545721;4263.232618128766;4271.466066741657;4272.46594175728;4284.464441944757;4525.818967241339;4985.351904252497;5789.1026890831145;5801.361850196475;5811.912821307684;6082.756689732361;6091.390860913962;6108.175396321529;6120.479759220516;6120.938790612126;5569.192467092249;5813.5349172066135;5828.737706803204;5843.707814609253;5844.736986835603;5162.277281180638;5206.895862082737;5230.555402790806;5232.947670523322;5242.292290540804;6336.112944187817;6341.397567231198;6354.23749150105;6357.650329231937;6382.712777924911;6103.3591472894705;6124.846878827887;6135.754569817336;6138.202033735586;6143.170671959407;6147.415995480422;6202.162708034358;6235.407636274546;6280.811575652738;6313.05304204373;6022.397760223943;6027.276726792813;6034.064719968412;6049.848753781191;6075.210222671025;6060.2424696912885;6093.969530152321;6106.1146133811235;6113.694315284129;6115.480184184395;5732.426757324234;5803.013487707184;5804.303483581997;5833.504152146946;5878.353381128158;5642.689652069175;5874.735636896265;5909.172715819703;5924.6149000314845;5958.374370691188;4618.815247390066;4706.247000479907;4720.740359280273;4838.951610483991;5740.770369185353;5705.971470142623;5755.683437410815;5895.64626122434;5895.94104058954;5915.142304365757;4659.767011649472;5698.002849501404;5711;5712.400197979318;5751.396103409082 09:22:31 INFO - PROCESS | 1636 | |1;ext.html;444.2935732185822;491.5256777209992;515.7343967856552;529.5445916511799;535.1811728056075;38022.61977380229;39606.40590391143;39709.8014509927;39716.007099822484;39734.40398394026;39157;39350;39523.802380988054;39632.603673963255;39634;9304.720858374243;9458.243340532741;9468.905310946906;9469.715908522756;9503.904960950405;9305.720828375159;9314.86027709585;9396.530173491332;9471.715848524555;9501.572429240701;9432.669856555021;9442.480663563501;9488.952555237214;9490.383125096881;9525.761855953588;9312.580933857926;9401.717948461557;9468.289878259124;9475.857862132005;9485.288603354707;9353.90646093544;9375.578098985494;9454.763630909214;9462.337636365457;9467.66863159793;39115;39128.217435651124;39273;39348.40977385342;39378.80310598428;3343.147497388175;3399.507071474647;3560.6973407260416;3656.323580137683;3810.40938654509;28410.71589284122;28718.569221461694;29072.12783616495;29906.70093299061;30492.54261186084;3145.2608636970294;3188.5695431116733;3203.8398080095903;3221.387936292097;3224.8387580620874;415078;416818;417028;417740;418637.906810464;55218.72390638021;55242.72378638081;55573.722131389084;55678.72160639171;55774;56940;57295.42704572901;58129.70935145297;58172.12741808876;58245.126323105185;13174.209547427185;15814.367425302858;15816.288267027781;15832;15881.682366352608;12242.877571224351;12330.64362920096;12345.814812777815;12356.814647780111;12365.938170309271;12418.441170147365;14563.563093107225;14949.402023918921;14974.026688265229;14985.925070374578;3159.605049368829;5557.777688892399;6180.969095154495;6206.348333425016;6350.555461117678;856.3876828067961;938.0900526489295;990.4503000834605;1012.5595366015659;1032.5353590883983;23751.52496950086;28342.574861377096;29162.562561561594;29320.560191596716;29505.557416638767;936.5083331251147;1045.2369770067894;1114.593173491686;1117.3631030312645;1132.6262333429947;12047.879521204852;12830.615081547568;14070.577882663536;14155.796757275746;14607.342669579895;653931;657973;663003;664183;664461;54083.91832163413;54587.727061364436;55031.44968550343;55146.62133446696;55447.722761385936;1139.5669645534645;1152.4180288954024;1196.976060478803;1371.2252577293798;1387.5837248825515;23904.760952390254;25484.362890927874;29598.260043499085;29624.63002959801;29662;47236.52763472459;47509.76245118891;47550.76224618855;47610.809729757035;47905.76047119882;485798;579472;581993;582498.0875095597;583731.0813445905;25585.744142558335;25602.87198563995;25723;25999;26092.478150437266;43375;44124.77937610292;44343.556564433944;44419.555804441545;44474.555254448336;36829.63170368262;37651.246975060894;37668.43497347597;37716.81141594274;39013.02467438337;29862;37314.8134259327;37843.43234851534;37957.810210948766;38077.61922380741;36397.0900727484;36585.817070914476;36631;37122.143892804714;37177;27512.724872751016;31858.36283274275;33552.832235838665;33748.325033499685;33788.155296117795;24577.75422245826;24879.50240995134;24971.75028249694;25209.99160033578;25377.238682839543 09:22:31 INFO - PROCESS | 1636 | |2;jquery.html;371.9349113905068;372.2852123922069;383.1704360060468;384.1012031845481;398.8723608445297;18805.529861753465;19341.22635094598;20575.38273851787;20672.27647032354;20798.376048718525;17404.39084632038;20378.69431958522;20387.592248155066;20689.586208275825;20799.376018719424;4703.929441058387;4998.700077995323;5969.5821292509545;5979.252593425822;6581.2760596334365;6584.736610535573;6640;6684.933150668479;6690.799276021728;6724.394804467597;4739.573438390544;5997.970010149943;6030.33666296707;6062.878367502026;6653.567518111307;5924.585279030491;6001.129836173752;6024.09638554216;6102.633841969496;6207.534434917399;5800.042992906166;5842.853928651775;5853;5855.268091488564;5861.560382971252;12951.740965180737;13102.80345794814;25159.874200629063;26385;26398.472030559282;2468.925932222018;2573.3052075939577;2575.085844525185;2985.7163569461086;3195.328980914011;14215.218163001031;15364.846351536453;16132.838671613252;16376.426825061184;17044.744328835077;1908.2235347569253;1945.9124339404761;2051.466618679139;2053.37372101509;2055.7122002919455;116046.41976790062;118143.4092829539;121839;130850.69149308576;136994;20009.499762505762;21137.943102844798;21299.574008519743;22000.44998875041;22444.88777556102;35377.87732736044;35504.467432988524;35747.285054298765;36099.81950090285;36168.457473137925;10533.736656583647;10669.519871605797;10811;10965.232433729563;11014;8785.736427907173;8843.292536597057;8843.91156088431;8926.955365223263;8960.79029331045;9646.324757266924;9798.510074496247;9800.264980126376;9828.754281143028;9884.011598840058;3484.884836852183;3653.6163702811355;3854.807259637007;3864.304425203434;3886.7473614214987;747.9155224923882;786.8898354230412;874.7534762962753;926.6525053105086;963.7108867339911;9426.340156189139;20340.69488957667;21082.367528974155;21145;21634.459138521663;365.554024090609;418.35155508961594;432.4831825968001;443.025344242661;450.69127647561527;4662.790174442158;5032.547070763649;5277.287566178594;5521.757604539027;5881.882362352814;245960;248720;248898;517177.82822172047;593220;77174.68475945618;82094.58952705318;130667.34666326607;135188;135237;434.19890302392037;444.8887778055486;447.88802799300174;449.17576247586044;449.43595787286745;10108.545115469675;10571.942805719367;11071.72320691989;11436.199466037298;11644.941775291069;48145.75927120482;51344;52450.47549524456;52978.735106324224;53178.73410632922;64074.35925640684;73000.26999729934;75735;76037.23962760302;76252;7498.137714162941;8006.919930800617;8010.799730006796;8016.478928869605;8076;8179.918200817916;12050.939745301568;12079.214851035005;12282.938585307016;12308.569200077955;8082.878756818534;10648.627298044532;10658.774240962079;10776.67669969902;11028.172887033345;6677.697848919414;8614.741557753277;10179.898201017895;10535.42055186972;10713.142948564253;10718.6065811444;10800.405977671304;10860.348379097279;10863;11176.161787866105;8071.59642017885;8093.392995525477;8115.9855018122735;8146.14465481116;8171.713990010321;9858.471936849799;10258.794824103625;10375.16998640122;10527.052565269163;11000.614978475714 09:22:31 INFO - PROCESS | 1636 | |3;mootools.html;1476.6160798192466;1510.3656464285002;1696.4138530474008;1767.6199617082325;1823.0337920901918;7487.625618719056;7778;8066.5966701664975;8518.999999999993;8534.487930724154;8037.91962080379;8319.251267385935;8326.500409975406;8422.19989101036;8424.115467875878;2329.8951547180377;2349.3656712687566;2351.670766092748;2354.9411264718433;2358.5518751437257;2341.566810140121;2369.668246445499;2383.52329534093;2392.1986134644917;2394.162043284854;2293.2776175504746;2299.6665483504967;2325.2907863101746;2330.1611419888827;2333.3349995251356;2295.896684649195;2297.9195728149516;2314.2016004478514;2319.385362878847;2331.25399872041;2327.476317828492;2352.0826877517784;2355.128602417117;2356.8939397727145;2359.044586942291;6902.965485172592;8391.202835730595;8435.198656127657;8473.322134229247;8516.403851730382;4775.641826863;5017.89964200714;5114.156164232897;5199.532042116228;5221.55616772571;3405.642407547197;3453.240287136826;3496.790192588453;3602.7117830573493;3614.9277014459826;861.3238607692931;901.8872640919885;971.4511301114848;1014.4065721552901;1117.3966058328492;9490.861096668375;10771.353718776898;10859.78280434387;10864.78270434587;10966.725831854188;4511.774411279423;4596.977015114903;4668.766561671903;4673.929891051637;4742.7865746040825;7952.244536768998;9334.766630834147;9401.200897923616;9467.479288639053;9642.228621710246;4357.215701173755;5173.068847607392;5175.922361164586;5187.533122019036;5199.532042116152;3916.4516967624554;4266.5093514246255;4429.75636339998;4595.172868883633;4735.763211839394;3778.6032466591164;3833.811518429296;3880.8447662093763;3901.08324543732;3908.413737939275;1082.9079528240031;1275.2603489975793;1280.6606249343931;1292.1149012926176;1295.559509766684;790.4506368074133;863.8142799298181;910.3354551177678;918.4030380252889;926.9536523173814;5439.5104440600535;5451.836444906659;5453.890922181534;5599.99200143978;5636.408177141423;924.8242833861544;963.0706368354431;982.886967998675;989.4656885282011;996.6163027234567;3673.68773654237;3909.3940439231965;3966.286068507696;4028.7582745035393;4038.273110840018;9895.30732848708;10216.693499195037;10231.181505479544;10233.43716095607;10248.180145588334;9724.416535007922;9868.358556693793;10161.136303414292;10182.18542516597;10189.745256368502;7464.888026679604;7746.147923728498;7769.300762931363;7921.485103468256;7924.801879953047;5804.941950580609;5892.410758924245;5951.434613711691;5989.371116032754;6003.879922401615;9215.953920230357;9344.112309330603;9434.433933963985;9445;9460.195883349992;8988.146126118008;9415.870095588572;9422.528873556432;9454.432734035705;9477.336586438883;6633.9336606633315;6853.588784672736;7009.1939426966555;7170.784876453506;7302.890456643261;7795.298423141944;8035.397345199017;8037.718679846177;8051.114377418362;8058.597070146587;7063.611501367269;7241.746538871114;7250.456215783732;7271.636418179176;7323.231060738546;6919.238883722887;7029.5430796998035;7059.5764254144915;7161.749338773118;7178.676959536938;6609.768658096944;6621.106150669598;6622.8344291393105;6705.396514313735;6712.597244165366;4117.382392641128;4834.540718631724;4839.370881785346;4895.412550493964;4915.557599816034;4186.602272784081;4751.429828420612;4874.878128046827;4902.779374928065;4989.950100498949 09:22:31 INFO - PROCESS | 1636 | |4;prototype.html;282.7610668984708;301.14775186222994;307.3530218889238;311.1598683554402;311.40366198729436;4898.314236006958;5087.694738315695;5245.639462241997;5314.840554783353;5334.03987282289;4253.680973926952;4489.977550112253;4917.057762183935;5115.391150513237;5181.6113791465605;3770.8114594270314;3926.8429262829454;4033.475648165734;4037.7375470594316;4039.313316736147;3350.6481819408978;3748;4045.8381664733524;4051.9262395465225;4056.553779084298;3365.697087262146;3545.503629491873;3939.5666476687543;3960.386140148282;3978.4032395140816;3610.801405922673;3942;3946.782926939017;3953.5058117735284;3963.286608410485;2854.5290027145497;2921.4595299869593;3412.6861158453926;3423.3324501722172;3437.8968630941113;4646.838290427393;5068.4171320298265;5085.0844849466075;5118.283440318359;5144.180845115404;3859.55615104261;4442.844500442501;4454.507739907135;4474.657602719171;4502.549745025504;5189.455107213726;5462.852800911814;5506.201600767866;5508.3114610673665;5526.778928842841;3574.428091505373;3688.5389326334207;3738.1776009277914;3761.247750449921;3791.2796568652143;5041.706023759218;5720.342160651537;5741.511971482429;5748.160588611646;5753.741081651336;3047.7409420199533;3788.9431658525145;3797.8480860765812;3821.84712611498;3846.9230615388096;3394.9490757638655;3867.5358956925347;3887.3002859485;3899.922001559953;3902.6097390261316;4048.149888523443;4325.545817689161;4348.652107831366;4476.052916981123;4510.436754963607;5087.491250874957;5296.285001524822;5578.665280083208;5601.60788744784;5647.745851436695;1114.7603265297837;1125.5666568371073;1172.284906207213;1202.6350092644736;1204.9395533637403;759.3659294489053;761.46316846623;805.3114587028124;824.2952275804101;863.1886027134474;4094.61101195386;4228.112096459717;4543.7728113594185;4795.712257264575;5066.442691303953;5206.791728330824;5363.946360536423;5389.461053894657;5400.784823414779;5497.752601132958;4361.367601697736;4500.909981800346;4520.751358675305;4893.559579637849;5145.716985565829;2333.742062186598;2353.5881220786296;2511.2717311979322;2816.549601846407;2855.4146399987953;5080.348886611866;5193.468441459768;5387.434319396407;5760.913586296209;5776.57002417726;4608.078384323148;4711.293306004059;5424.267723857308;5559.694216818033;5569.799929028011;4651.95331050511;5129.743512824344;5153.871153221199;5219.869503262373;5250.849490305786;5076.593872490118;5406.567474601944;5457.590680698884;5475.370332411823;5507.9449205507435;4331.003869110059;5164.32863727713;5312.760925758273;5359.651622644516;5436.939796739598;5738.741756621045;5763.760791429695;5767.971160144172;5774.82675519735;5826.7086645666695;2968.9703102968692;3035.694651299962;3403.982980085084;3536.1513236823494;3646.9452958205115;4829.855104346875;5061.658660455056;5366.919496207482;5413.296271484839;5436.429174936575;4650.813967441264;5269.947300527099;5343.62594618373;5469.097598896137;5543.584231182596;4460.353248778844;5288.5985213917575;5518.33779946409;5531.2532808072;5540.307461567304;4828.010257897193;4971.78755453834;5092.414372347227;5238.71187084714;5395.78416863321;4383.860196349036;4452.5992660660695;4743.928841067318;5191.662541934762;5208.401033881152;4421.911561768682;4897.877553061059;5149.742512874266;5483.958047970873;5501.614886957875 09:22:31 INFO - PROCESS | 1636 | |5;yui.html;682.2018238660767;698.183125742881;760.7337431898833;761.756238003839;792.6393490961611;13982.230977296245;14761.261936903165;14789;14796.6300842479;14820.258987050658;13077.869221307785;13091.083624146313;13116.34418279087;13131.343432828367;13173.934130329359;615.0743131586967;645.8611398549311;784.5684873319651;795.6658203554492;812.0580127052591;802.9156938521459;808.8422757562278;809.1786836361098;809.2837838512929;812.5733989655419;849.6261644876265;850.5917159763318;854.0050840770494;854.3122786157172;855.2601999270618;829.7884039569901;834.0783434305066;843.5782108945527;845.6828689241565;847.2078606502939;620.4694985787178;629.625372903122;637.2894222941434;637.977670781525;639.2584601861864;13388.866111338957;13974;13987.720245595134;14010.789838152436;14276.928615356857;11351.545938162546;13465;13618.523351682643;13739.587812365646;14053.437862485489;10741;10752.301100428447;10928.344299341985;11188.776224475465;11322.773544529064;3517.419625761772;3597.4244120940525;3606.783592984429;3613.2773445311045;3728.0120767996505;63413.68293158505;83639.58180209121;85321;87349;87561.12438875594;9948.00519948014;10491.632792852213;10788.46057697112;10849.620263290746;10857.782844343226;18873.62252754937;19302.938338391243;19399.515012124528;19451.513712157026;19472.902635487015;6210.968945155245;7350.08123984502;7410.62946852666;7488.513246638951;7525.172231054579;4177.728447650893;4208.579142085767;4291.699581029298;4299.376590394375;4305.31115021595;3229.80621162731;3249.951250731194;3320.900372988814;3332.3168750406107;3340.0146956647845;618.7957973868703;618.9938100619026;625.9530535209877;628.3339659960457;639.3542522052667;606.2088973889029;609.8810731907324;611.6849822341497;616.5356239825427;625.3496363781707;8407.95796021016;8556.229939305367;8610.13898610134;8642.22220000203;8648.827023459495;506.94423613402495;538.2813943385546;585.338567418814;622.4895585619839;636.328673249726;3456.395130852091;3525.506429099928;3601.333753255604;4196.328587425997;4222.092250166229;72366.19084522928;74402.62798685972;110953;116082;120994;65828;67699.66150169217;68961.65519172372;93896.53051734834;98568.50715746375;63924;73981.26018739743;78144.60927695326;85758;87883;9253.814923701622;9411;9702.854457183006;9765.951170244387;9772.26707996889;41565.58434415738;49643;52547.423577291214;54795.726021369635;55114;65149.674251628436;73353.53292934004;78099.60950195213;80976.19023809687;81437;12045.81931271014;12052.758944820878;12068.75862482763;12102.939485302517;12478.438470268678;13383.330833458484;13386.129901556757;13422.798658020332;13572.389242484309;13656.863431365558;12579.182353147016;12581.685457863627;12708.682282943;12849.229046257255;13102.672433189247;10551.419671918118;11611.47748351343;11829.822552661544;12500.621204440678;12788.872111278768;11986.820197696867;12126.211796233216;12146.149769516402;12370.195937264049;12478.313692746986;10221.846672299773;10437.425941572983;10723.410212438079;10822.729431764268;10929;10607.575696972342;11123.777524449304;11130.443477825915;11141.832872506757;11162.832557511481 09:22:31 INFO - PROCESS | 1636 | __end_tp_report 09:22:31 INFO - PROCESS | 1636 | __start_cc_report 09:22:31 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,366 09:22:31 INFO - PROCESS | 1636 | __end_cc_report 09:22:31 INFO - PROCESS | 1636 | __startTimestamp1483550551486__endTimestamp 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:22:31 INFO - PROCESS | 1636 | Number of tests: 6 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5521.12 Median:5751.40 stddev:705.51 (12.3%) stddev-sans-first:670.68 09:22:31 INFO - PROCESS | 1636 | Values: 2647.0 3308.9 3345.0 3350.0 3370.1 5503.4 5524.1 5546.0 5562.9 5615.8 4300.8 5572.3 5583.4 5585.3 5608.3 5109.4 5137.4 5174.8 5180.8 5201.9 5154.7 5191.2 5199.0 5212.7 5227.8 4929.2 5060.8 5183.6 5196.2 5200.6 3715.3 3797.6 4278.8 5152.5 5162.3 4101.4 5114.1 5117.8 5129.7 5173.2 5550.8 5605.6 5628.7 5636.9 5644.7 5816.3 5874.6 5875.5 5942.7 6009.8 6040.4 6045.9 6068.7 6129.6 6176.3 5545.0 5556.4 5569.3 5598.1 5600.7 6151.4 6180.0 6205.0 6242.4 6257.5 5719.2 5772.7 5857.8 5886.0 5901.8 6080.6 6093.7 6095.3 6100.4 6144.8 4932.5 6013.4 6031.4 6032.8 6045.4 5799.2 5799.6 5842.9 5921.3 6032.7 4691.2 4719.6 4744.5 4751.2 4756.3 4254.6 4263.2 4271.5 4272.5 4284.5 4525.8 4985.4 5789.1 5801.4 5811.9 6082.8 6091.4 6108.2 6120.5 6120.9 5569.2 5813.5 5828.7 5843.7 5844.7 5162.3 5206.9 5230.6 5232.9 5242.3 6336.1 6341.4 6354.2 6357.7 6382.7 6103.4 6124.8 6135.8 6138.2 6143.2 6147.4 6202.2 6235.4 6280.8 6313.1 6022.4 6027.3 6034.1 6049.8 6075.2 6060.2 6094.0 6106.1 6113.7 6115.5 5732.4 5803.0 5804.3 5833.5 5878.4 5642.7 5874.7 5909.2 5924.6 5958.4 4618.8 4706.2 4720.7 4839.0 5740.8 5706.0 5755.7 5895.6 5895.9 5915.1 4659.8 5698.0 5711.0 5712.4 5751.4 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:67104.59 Median:39215.00 stddev:149010.99 (380.0%) stddev-sans-first:149344.95 09:22:31 INFO - PROCESS | 1636 | Values: 444.3 491.5 515.7 529.5 535.2 38022.6 39606.4 39709.8 39716.0 39734.4 39157.0 39350.0 39523.8 39632.6 39634.0 9304.7 9458.2 9468.9 9469.7 9503.9 9305.7 9314.9 9396.5 9471.7 9501.6 9432.7 9442.5 9489.0 9490.4 9525.8 9312.6 9401.7 9468.3 9475.9 9485.3 9353.9 9375.6 9454.8 9462.3 9467.7 39115.0 39128.2 39273.0 39348.4 39378.8 3343.1 3399.5 3560.7 3656.3 3810.4 28410.7 28718.6 29072.1 29906.7 30492.5 3145.3 3188.6 3203.8 3221.4 3224.8 415078.0 416818.0 417028.0 417740.0 418637.9 55218.7 55242.7 55573.7 55678.7 55774.0 56940.0 57295.4 58129.7 58172.1 58245.1 13174.2 15814.4 15816.3 15832.0 15881.7 12242.9 12330.6 12345.8 12356.8 12365.9 12418.4 14563.6 14949.4 14974.0 14985.9 3159.6 5557.8 6181.0 6206.3 6350.6 856.4 938.1 990.5 1012.6 1032.5 23751.5 28342.6 29162.6 29320.6 29505.6 936.5 1045.2 1114.6 1117.4 1132.6 12047.9 12830.6 14070.6 14155.8 14607.3 653931.0 657973.0 663003.0 664183.0 664461.0 54083.9 54587.7 55031.4 55146.6 55447.7 1139.6 1152.4 1197.0 1371.2 1387.6 23904.8 25484.4 29598.3 29624.6 29662.0 47236.5 47509.8 47550.8 47610.8 47905.8 485798.0 579472.0 581993.0 582498.1 583731.1 25585.7 25602.9 25723.0 25999.0 26092.5 43375.0 44124.8 44343.6 44419.6 44474.6 36829.6 37651.2 37668.4 37716.8 39013.0 29862.0 37314.8 37843.4 37957.8 38077.6 36397.1 36585.8 36631.0 37122.1 37177.0 27512.7 31858.4 33552.8 33748.3 33788.2 24577.8 24879.5 24971.8 25210.0 25377.2 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:28874.52 Median:383.64 stddev:69437.59 (18099.9%) stddev-sans-first:69599.40 09:22:31 INFO - PROCESS | 1636 | Values: 371.9 372.3 383.2 384.1 398.9 18805.5 19341.2 20575.4 20672.3 20798.4 17404.4 20378.7 20387.6 20689.6 20799.4 4703.9 4998.7 5969.6 5979.3 6581.3 6584.7 6640.0 6684.9 6690.8 6724.4 4739.6 5998.0 6030.3 6062.9 6653.6 5924.6 6001.1 6024.1 6102.6 6207.5 5800.0 5842.9 5853.0 5855.3 5861.6 12951.7 13102.8 25159.9 26385.0 26398.5 2468.9 2573.3 2575.1 2985.7 3195.3 14215.2 15364.8 16132.8 16376.4 17044.7 1908.2 1945.9 2051.5 2053.4 2055.7 116046.4 118143.4 121839.0 130850.7 136994.0 20009.5 21137.9 21299.6 22000.4 22444.9 35377.9 35504.5 35747.3 36099.8 36168.5 10533.7 10669.5 10811.0 10965.2 11014.0 8785.7 8843.3 8843.9 8927.0 8960.8 9646.3 9798.5 9800.3 9828.8 9884.0 3484.9 3653.6 3854.8 3864.3 3886.7 747.9 786.9 874.8 926.7 963.7 9426.3 20340.7 21082.4 21145.0 21634.5 365.6 418.4 432.5 443.0 450.7 4662.8 5032.5 5277.3 5521.8 5881.9 245960.0 248720.0 248898.0 517177.8 593220.0 77174.7 82094.6 130667.3 135188.0 135237.0 434.2 444.9 447.9 449.2 449.4 10108.5 10571.9 11071.7 11436.2 11644.9 48145.8 51344.0 52450.5 52978.7 53178.7 64074.4 73000.3 75735.0 76037.2 76252.0 7498.1 8006.9 8010.8 8016.5 8076.0 8179.9 12050.9 12079.2 12282.9 12308.6 8082.9 10648.6 10658.8 10776.7 11028.2 6677.7 8614.7 10179.9 10535.4 10713.1 10718.6 10800.4 10860.3 10863.0 11176.2 8071.6 8093.4 8116.0 8146.1 8171.7 9858.5 10258.8 10375.2 10527.1 11000.6 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5434.86 Median:5199.53 stddev:2957.39 (56.9%) stddev-sans-first:2950.73 09:22:31 INFO - PROCESS | 1636 | Values: 1476.6 1510.4 1696.4 1767.6 1823.0 7487.6 7778.0 8066.6 8519.0 8534.5 8037.9 8319.3 8326.5 8422.2 8424.1 2329.9 2349.4 2351.7 2354.9 2358.6 2341.6 2369.7 2383.5 2392.2 2394.2 2293.3 2299.7 2325.3 2330.2 2333.3 2295.9 2297.9 2314.2 2319.4 2331.3 2327.5 2352.1 2355.1 2356.9 2359.0 6903.0 8391.2 8435.2 8473.3 8516.4 4775.6 5017.9 5114.2 5199.5 5221.6 3405.6 3453.2 3496.8 3602.7 3614.9 861.3 901.9 971.5 1014.4 1117.4 9490.9 10771.4 10859.8 10864.8 10966.7 4511.8 4597.0 4668.8 4673.9 4742.8 7952.2 9334.8 9401.2 9467.5 9642.2 4357.2 5173.1 5175.9 5187.5 5199.5 3916.5 4266.5 4429.8 4595.2 4735.8 3778.6 3833.8 3880.8 3901.1 3908.4 1082.9 1275.3 1280.7 1292.1 1295.6 790.5 863.8 910.3 918.4 927.0 5439.5 5451.8 5453.9 5600.0 5636.4 924.8 963.1 982.9 989.5 996.6 3673.7 3909.4 3966.3 4028.8 4038.3 9895.3 10216.7 10231.2 10233.4 10248.2 9724.4 9868.4 10161.1 10182.2 10189.7 7464.9 7746.1 7769.3 7921.5 7924.8 5804.9 5892.4 5951.4 5989.4 6003.9 9216.0 9344.1 9434.4 9445.0 9460.2 8988.1 9415.9 9422.5 9454.4 9477.3 6633.9 6853.6 7009.2 7170.8 7302.9 7795.3 8035.4 8037.7 8051.1 8058.6 7063.6 7241.7 7250.5 7271.6 7323.2 6919.2 7029.5 7059.6 7161.7 7178.7 6609.8 6621.1 6622.8 6705.4 6712.6 4117.4 4834.5 4839.4 4895.4 4915.6 4186.6 4751.4 4874.9 4902.8 4990.0 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4318.14 Median:4828.93 stddev:1351.08 (28.0%) stddev-sans-first:1320.48 09:22:31 INFO - PROCESS | 1636 | Values: 282.8 301.1 307.4 311.2 311.4 4898.3 5087.7 5245.6 5314.8 5334.0 4253.7 4490.0 4917.1 5115.4 5181.6 3770.8 3926.8 4033.5 4037.7 4039.3 3350.6 3748.0 4045.8 4051.9 4056.6 3365.7 3545.5 3939.6 3960.4 3978.4 3610.8 3942.0 3946.8 3953.5 3963.3 2854.5 2921.5 3412.7 3423.3 3437.9 4646.8 5068.4 5085.1 5118.3 5144.2 3859.6 4442.8 4454.5 4474.7 4502.5 5189.5 5462.9 5506.2 5508.3 5526.8 3574.4 3688.5 3738.2 3761.2 3791.3 5041.7 5720.3 5741.5 5748.2 5753.7 3047.7 3788.9 3797.8 3821.8 3846.9 3394.9 3867.5 3887.3 3899.9 3902.6 4048.1 4325.5 4348.7 4476.1 4510.4 5087.5 5296.3 5578.7 5601.6 5647.7 1114.8 1125.6 1172.3 1202.6 1204.9 759.4 761.5 805.3 824.3 863.2 4094.6 4228.1 4543.8 4795.7 5066.4 5206.8 5363.9 5389.5 5400.8 5497.8 4361.4 4500.9 4520.8 4893.6 5145.7 2333.7 2353.6 2511.3 2816.5 2855.4 5080.3 5193.5 5387.4 5760.9 5776.6 4608.1 4711.3 5424.3 5559.7 5569.8 4652.0 5129.7 5153.9 5219.9 5250.8 5076.6 5406.6 5457.6 5475.4 5507.9 4331.0 5164.3 5312.8 5359.7 5436.9 5738.7 5763.8 5768.0 5774.8 5826.7 2969.0 3035.7 3404.0 3536.2 3646.9 4829.9 5061.7 5366.9 5413.3 5436.4 4650.8 5269.9 5343.6 5469.1 5543.6 4460.4 5288.6 5518.3 5531.3 5540.3 4828.0 4971.8 5092.4 5238.7 5395.8 4383.9 4452.6 4743.9 5191.7 5208.4 4421.9 4897.9 5149.7 5484.0 5501.6 09:22:31 INFO - PROCESS | 1636 | 09:22:31 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19181.97 Median:4295.54 stddev:27635.86 (643.4%) stddev-sans-first:27678.48 09:22:31 INFO - PROCESS | 1636 | Values: 682.2 698.2 760.7 761.8 792.6 13982.2 14761.3 14789.0 14796.6 14820.3 13077.9 13091.1 13116.3 13131.3 13173.9 615.1 645.9 784.6 795.7 812.1 802.9 808.8 809.2 809.3 812.6 849.6 850.6 854.0 854.3 855.3 829.8 834.1 843.6 845.7 847.2 620.5 629.6 637.3 638.0 639.3 13388.9 13974.0 13987.7 14010.8 14276.9 11351.5 13465.0 13618.5 13739.6 14053.4 10741.0 10752.3 10928.3 11188.8 11322.8 3517.4 3597.4 3606.8 3613.3 3728.0 63413.7 83639.6 85321.0 87349.0 87561.1 9948.0 10491.6 10788.5 10849.6 10857.8 18873.6 19302.9 19399.5 19451.5 19472.9 6211.0 7350.1 7410.6 7488.5 7525.2 4177.7 4208.6 4291.7 4299.4 4305.3 3229.8 3250.0 3320.9 3332.3 3340.0 618.8 619.0 626.0 628.3 639.4 606.2 609.9 611.7 616.5 625.3 8408.0 8556.2 8610.1 8642.2 8648.8 506.9 538.3 585.3 622.5 636.3 3456.4 3525.5 3601.3 4196.3 4222.1 72366.2 74402.6 110953.0 116082.0 120994.0 65828.0 67699.7 68961.7 93896.5 98568.5 63924.0 73981.3 78144.6 85758.0 87883.0 9253.8 9411.0 9702.9 9766.0 9772.3 41565.6 49643.0 52547.4 54795.7 55114.0 65149.7 73353.5 78099.6 80976.2 81437.0 12045.8 12052.8 12068.8 12102.9 12478.4 13383.3 13386.1 13422.8 13572.4 13656.9 12579.2 12581.7 12708.7 12849.2 13102.7 10551.4 11611.5 11829.8 12500.6 12788.9 11986.8 12126.2 12146.1 12370.2 12478.3 10221.8 10437.4 10723.4 10822.7 10929.0 10607.6 11123.8 11130.4 11141.8 11162.8 09:22:31 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:22:31 INFO - PROCESS | 1636 | 09:22:32 INFO - TEST-INFO | 1636: exit 0 09:22:32 INFO - TEST-OK | dromaeo_css | took 1115890ms 09:22:32 INFO - TEST-START | kraken 09:22:32 INFO - operating with platform_type : mac_ 09:22:32 INFO - Initialising browser for kraken test... 09:22:32 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpFk__r0/profile http://localhost:49241/getInfo.html) 09:22:35 INFO - PROCESS | 1647 | 2017-01-04 09:22:35.189 plugin-container[1648:10245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 09:22:35 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:35 INFO - PROCESS | 1647 | 2017-01-04 09:22:35.192 plugin-container[1648:10245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x861b, name = 'com.apple.CFPasteboardClient' 09:22:35 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:35 INFO - PROCESS | 1647 | 2017-01-04 09:22:35.192 plugin-container[1648:10245] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:35 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:22:35 INFO - PROCESS | 1647 | colorDepth:24 09:22:35 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:22:35 INFO - PROCESS | 1647 | __metrics 09:22:35 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:22:36 INFO - TEST-INFO | 1647: exit 0 09:22:36 INFO - Browser initialized. 09:22:36 INFO - Running cycle 1/1 for kraken test... 09:22:36 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpFk__r0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:38 INFO - PROCESS | 1649 | 2017-01-04 09:22:38.777 plugin-container[1650:10369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864f, name = 'com.apple.tsm.portname' 09:22:38 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:38 INFO - PROCESS | 1649 | 2017-01-04 09:22:38.778 plugin-container[1650:10369] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8813, name = 'com.apple.CFPasteboardClient' 09:22:38 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:38 INFO - PROCESS | 1649 | 2017-01-04 09:22:38.778 plugin-container[1650:10369] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:48 INFO - PROCESS | 1649 | RSS: Main: 229548032 09:22:48 INFO - PROCESS | 1649 | 09:22:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:52 INFO - PROCESS | 1649 | RSS: Main: 241766400 09:22:52 INFO - PROCESS | 1649 | 09:22:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:22:55 INFO - PROCESS | 1649 | RSS: Main: 249438208 09:22:55 INFO - PROCESS | 1649 | 09:22:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:22:59 INFO - PROCESS | 1649 | RSS: Main: 253132800 09:22:59 INFO - PROCESS | 1649 | 09:23:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:23:02 INFO - PROCESS | 1649 | RSS: Main: 254447616 09:23:02 INFO - PROCESS | 1649 | 09:23:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:23:05 INFO - PROCESS | 1649 | RSS: Main: 254615552 09:23:05 INFO - PROCESS | 1649 | 09:23:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:23:08 INFO - PROCESS | 1649 | RSS: Main: 259014656 09:23:08 INFO - PROCESS | 1649 | 09:23:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:23:12 INFO - PROCESS | 1649 | RSS: Main: 258433024 09:23:12 INFO - PROCESS | 1649 | 09:23:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:23:16 INFO - PROCESS | 1649 | RSS: Main: 258207744 09:23:16 INFO - PROCESS | 1649 | 09:23:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:18 INFO - PROCESS | 1649 | RSS: Main: 257277952 09:23:18 INFO - PROCESS | 1649 | 09:23:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:20 INFO - PROCESS | 1649 | RSS: Main: 257306624 09:23:20 INFO - PROCESS | 1649 | 09:23:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:23 INFO - PROCESS | 1649 | RSS: Main: 257318912 09:23:23 INFO - PROCESS | 1649 | 09:23:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:27 INFO - PROCESS | 1649 | RSS: Main: 257667072 09:23:27 INFO - PROCESS | 1649 | 09:23:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:31 INFO - PROCESS | 1649 | RSS: Main: 257548288 09:23:31 INFO - PROCESS | 1649 | 09:23:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:23:33 INFO - PROCESS | 1649 | RSS: Main: 259350528 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | __start_tp_report 09:23:33 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:23:33 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:23:33 INFO - PROCESS | 1649 | |i|pagename|runs| 09:23:33 INFO - PROCESS | 1649 | |0;ai-astar;105;117;120;118;117;119;121;120;119;121 09:23:33 INFO - PROCESS | 1649 | |1;audio-beat-detection;148;126;150;182;119;110;111;123;166;104 09:23:33 INFO - PROCESS | 1649 | |2;audio-dft;210;239;275;207;194;199;194;218;194;195 09:23:33 INFO - PROCESS | 1649 | |3;audio-fft;81;93;80;93;60;59;68;49;90;49 09:23:33 INFO - PROCESS | 1649 | |4;audio-oscillator;81;70;96;88;100;86;85;70;70;89 09:23:33 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;87;117;85;127;120;89;89;89;86 09:23:33 INFO - PROCESS | 1649 | |6;imaging-darkroom;93;107;143;95;96;155;153;104;154;105 09:23:33 INFO - PROCESS | 1649 | |7;imaging-desaturate;85;138;135;86;90;73;132;142;136;89 09:23:33 INFO - PROCESS | 1649 | |8;json-parse-financial;57;56;57;60;60;61;59;59;60;60 09:23:33 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;68;78;89;79;108;70;71;72;76;73 09:23:33 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;83;82;92;92;105;94;87;83;85;86 09:23:33 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;143;161;154;159;165;153;163;165;206;162 09:23:33 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;170;182;167;228;200;182;166;166;165;170 09:23:33 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;69;66;67;69;68;68;68;69;68 09:23:33 INFO - PROCESS | 1649 | __end_tp_report 09:23:33 INFO - PROCESS | 1649 | __start_cc_report 09:23:33 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,995 09:23:33 INFO - PROCESS | 1649 | __end_cc_report 09:23:33 INFO - PROCESS | 1649 | __startTimestamp1483550613058__endTimestamp 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:23:33 INFO - PROCESS | 1649 | Number of tests: 14 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:117.70 Median:119.50 stddev:4.69 (3.9%) stddev-sans-first:1.54 09:23:33 INFO - PROCESS | 1649 | Values: 105.0 117.0 120.0 118.0 117.0 119.0 121.0 120.0 119.0 121.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:137.00 stddev:26.24 (19.2%) stddev-sans-first:27.33 09:23:33 INFO - PROCESS | 1649 | Values: 148.0 126.0 150.0 182.0 119.0 110.0 111.0 123.0 166.0 104.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:212.50 Median:208.50 stddev:26.23 (12.6%) stddev-sans-first:27.80 09:23:33 INFO - PROCESS | 1649 | Values: 210.0 239.0 275.0 207.0 194.0 199.0 194.0 218.0 194.0 195.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.20 Median:80.50 stddev:17.44 (21.7%) stddev-sans-first:18.21 09:23:33 INFO - PROCESS | 1649 | Values: 81.0 93.0 80.0 93.0 60.0 59.0 68.0 49.0 90.0 49.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:85.50 stddev:10.75 (12.6%) stddev-sans-first:11.37 09:23:33 INFO - PROCESS | 1649 | Values: 81.0 70.0 96.0 88.0 100.0 86.0 85.0 70.0 70.0 89.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:88.00 stddev:16.48 (18.7%) stddev-sans-first:17.17 09:23:33 INFO - PROCESS | 1649 | Values: 89.0 87.0 117.0 85.0 127.0 120.0 89.0 89.0 89.0 86.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:120.50 Median:154.50 stddev:27.03 (17.5%) stddev-sans-first:26.78 09:23:33 INFO - PROCESS | 1649 | Values: 93.0 107.0 143.0 95.0 96.0 155.0 153.0 104.0 154.0 105.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:110.60 Median:79.00 stddev:27.89 (35.3%) stddev-sans-first:28.00 09:23:33 INFO - PROCESS | 1649 | Values: 85.0 138.0 135.0 86.0 90.0 73.0 132.0 142.0 136.0 89.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:60.00 stddev:1.66 (2.8%) stddev-sans-first:1.62 09:23:33 INFO - PROCESS | 1649 | Values: 57.0 56.0 57.0 60.0 60.0 61.0 59.0 59.0 60.0 60.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:78.40 Median:74.50 stddev:12.01 (16.1%) stddev-sans-first:12.14 09:23:33 INFO - PROCESS | 1649 | Values: 68.0 78.0 89.0 79.0 108.0 70.0 71.0 72.0 76.0 73.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:86.50 stddev:7.06 (8.2%) stddev-sans-first:7.16 09:23:33 INFO - PROCESS | 1649 | Values: 83.0 82.0 92.0 92.0 105.0 94.0 87.0 83.0 85.0 86.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:163.10 Median:162.50 stddev:16.53 (10.2%) stddev-sans-first:15.85 09:23:33 INFO - PROCESS | 1649 | Values: 143.0 161.0 154.0 159.0 165.0 153.0 163.0 165.0 206.0 162.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:176.00 stddev:20.27 (11.5%) stddev-sans-first:21.20 09:23:33 INFO - PROCESS | 1649 | Values: 170.0 182.0 167.0 228.0 200.0 182.0 166.0 166.0 165.0 170.0 09:23:33 INFO - PROCESS | 1649 | 09:23:33 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 09:23:33 INFO - PROCESS | 1649 | Values: 68.0 69.0 66.0 67.0 69.0 68.0 68.0 68.0 69.0 68.0 09:23:33 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:23:33 INFO - PROCESS | 1649 | 09:23:34 INFO - TEST-INFO | 1649: exit 0 09:23:34 INFO - TEST-OK | kraken | took 61925ms 09:23:34 INFO - SUITE-END | took 1177s 09:23:34 INFO - Completed test suite (00:19:37) 09:23:34 INFO - javascript benchmark 09:23:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7006.810433177596, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2647.0, 3308.8841890533827, 3345.0, 3349.9615119313016, 3370.106921665759, 5503.367112782032, 5524.08852539331, 5545.972270138644, 5562.888742225163, 5615.775368985246, 4300.849470268541, 5572.3034620672415, 5583.35791383991, 5585.301837270341, 5608.298962629671, 5109.4379618241965, 5137.409197942247, 5174.793008279665, 5180.792768289264, 5201.9479805201845, 5154.664946778447, 5191.247269145972, 5198.974005129988, 5212.687238765668, 5227.79088836446, 4929.186684197103, 5060.772265248054, 5183.611229157809, 5196.220566914955, 5200.557952574017, 3715.256948610289, 3797.620237976208, 4278.764667943277, 5152.484751524855, 5162.3030890830005, 4101.384792281182, 5114.130597798383, 5117.820876269313, 5129.692218466904, 5173.224016397533, 5550.750216240279, 5605.5515558755205, 5628.662280263197, 5636.85907852307, 5644.717764111819, 5816.331121920991, 5874.647521148702, 5875.471207591295, 5942.673152976585, 6009.849753756147, 6040.395960403968, 6045.909311360333, 6068.726907289182, 6129.570930034899, 6176.258848938111, 5545.0, 5556.416576259475, 5569.275994120779, 5598.1322894951345, 5600.719964001865, 6151.415615516519, 6180.042093475518, 6205.0, 6242.43818056368, 6257.530685198628, 5719.170720245625, 5772.7113644317615, 5857.7656893724625, 5885.970570147208, 5901.793437229677, 6080.604760690541, 6093.69531523422, 6095.2685677718955, 6100.420460056288, 6144.815655530341, 4932.482089380635, 6013.398660133952, 6031.366706495754, 6032.788852390146, 6045.3652366501765, 5799.159121927381, 5799.594028418054, 5842.941570584325, 5921.3190483094995, 6032.728527216286, 4691.225947718656, 4719.575238228507, 4744.501827308152, 4751.239801631722, 4756.334113224151, 4254.574542545721, 4263.232618128766, 4271.466066741657, 4272.46594175728, 4284.464441944757, 4525.818967241339, 4985.351904252497, 5789.1026890831145, 5801.361850196475, 5811.912821307684, 6082.756689732361, 6091.390860913962, 6108.175396321529, 6120.479759220516, 6120.938790612126, 5569.192467092249, 5813.5349172066135, 5828.737706803204, 5843.707814609253, 5844.736986835603, 5162.277281180638, 5206.895862082737, 5230.555402790806, 5232.947670523322, 5242.292290540804, 6336.112944187817, 6341.397567231198, 6354.23749150105, 6357.650329231937, 6382.712777924911, 6103.3591472894705, 6124.846878827887, 6135.754569817336, 6138.202033735586, 6143.170671959407, 6147.415995480422, 6202.162708034358, 6235.407636274546, 6280.811575652738, 6313.05304204373, 6022.397760223943, 6027.276726792813, 6034.064719968412, 6049.848753781191, 6075.210222671025, 6060.2424696912885, 6093.969530152321, 6106.1146133811235, 6113.694315284129, 6115.480184184395, 5732.426757324234, 5803.013487707184, 5804.303483581997, 5833.504152146946, 5878.353381128158, 5642.689652069175, 5874.735636896265, 5909.172715819703, 5924.6149000314845, 5958.374370691188, 4618.815247390066, 4706.247000479907, 4720.740359280273, 4838.951610483991, 5740.770369185353, 5705.971470142623, 5755.683437410815, 5895.64626122434, 5895.94104058954, 5915.142304365757, 4659.767011649472, 5698.002849501404, 5711.0, 5712.400197979318, 5751.396103409082], "value": 5474.5333559802675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.2935732185822, 491.5256777209992, 515.7343967856552, 529.5445916511799, 535.1811728056075, 38022.61977380229, 39606.40590391143, 39709.8014509927, 39716.007099822484, 39734.40398394026, 39157.0, 39350.0, 39523.802380988054, 39632.603673963255, 39634.0, 9304.720858374243, 9458.243340532741, 9468.905310946906, 9469.715908522756, 9503.904960950405, 9305.720828375159, 9314.86027709585, 9396.530173491332, 9471.715848524555, 9501.572429240701, 9432.669856555021, 9442.480663563501, 9488.952555237214, 9490.383125096881, 9525.761855953588, 9312.580933857926, 9401.717948461557, 9468.289878259124, 9475.857862132005, 9485.288603354707, 9353.90646093544, 9375.578098985494, 9454.763630909214, 9462.337636365457, 9467.66863159793, 39115.0, 39128.217435651124, 39273.0, 39348.40977385342, 39378.80310598428, 3343.147497388175, 3399.507071474647, 3560.6973407260416, 3656.323580137683, 3810.40938654509, 28410.71589284122, 28718.569221461694, 29072.12783616495, 29906.70093299061, 30492.54261186084, 3145.2608636970294, 3188.5695431116733, 3203.8398080095903, 3221.387936292097, 3224.8387580620874, 415078.0, 416818.0, 417028.0, 417740.0, 418637.906810464, 55218.72390638021, 55242.72378638081, 55573.722131389084, 55678.72160639171, 55774.0, 56940.0, 57295.42704572901, 58129.70935145297, 58172.12741808876, 58245.126323105185, 13174.209547427185, 15814.367425302858, 15816.288267027781, 15832.0, 15881.682366352608, 12242.877571224351, 12330.64362920096, 12345.814812777815, 12356.814647780111, 12365.938170309271, 12418.441170147365, 14563.563093107225, 14949.402023918921, 14974.026688265229, 14985.925070374578, 3159.605049368829, 5557.777688892399, 6180.969095154495, 6206.348333425016, 6350.555461117678, 856.3876828067961, 938.0900526489295, 990.4503000834605, 1012.5595366015659, 1032.5353590883983, 23751.52496950086, 28342.574861377096, 29162.562561561594, 29320.560191596716, 29505.557416638767, 936.5083331251147, 1045.2369770067894, 1114.593173491686, 1117.3631030312645, 1132.6262333429947, 12047.879521204852, 12830.615081547568, 14070.577882663536, 14155.796757275746, 14607.342669579895, 653931.0, 657973.0, 663003.0, 664183.0, 664461.0, 54083.91832163413, 54587.727061364436, 55031.44968550343, 55146.62133446696, 55447.722761385936, 1139.5669645534645, 1152.4180288954024, 1196.976060478803, 1371.2252577293798, 1387.5837248825515, 23904.760952390254, 25484.362890927874, 29598.260043499085, 29624.63002959801, 29662.0, 47236.52763472459, 47509.76245118891, 47550.76224618855, 47610.809729757035, 47905.76047119882, 485798.0, 579472.0, 581993.0, 582498.0875095597, 583731.0813445905, 25585.744142558335, 25602.87198563995, 25723.0, 25999.0, 26092.478150437266, 43375.0, 44124.77937610292, 44343.556564433944, 44419.555804441545, 44474.555254448336, 36829.63170368262, 37651.246975060894, 37668.43497347597, 37716.81141594274, 39013.02467438337, 29862.0, 37314.8134259327, 37843.43234851534, 37957.810210948766, 38077.61922380741, 36397.0900727484, 36585.817070914476, 36631.0, 37122.143892804714, 37177.0, 27512.724872751016, 31858.36283274275, 33552.832235838665, 33748.325033499685, 33788.155296117795, 24577.75422245826, 24879.50240995134, 24971.75028249694, 25209.99160033578, 25377.238682839543], "value": 18915.128513566775, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.9349113905068, 372.2852123922069, 383.1704360060468, 384.1012031845481, 398.8723608445297, 18805.529861753465, 19341.22635094598, 20575.38273851787, 20672.27647032354, 20798.376048718525, 17404.39084632038, 20378.69431958522, 20387.592248155066, 20689.586208275825, 20799.376018719424, 4703.929441058387, 4998.700077995323, 5969.5821292509545, 5979.252593425822, 6581.2760596334365, 6584.736610535573, 6640.0, 6684.933150668479, 6690.799276021728, 6724.394804467597, 4739.573438390544, 5997.970010149943, 6030.33666296707, 6062.878367502026, 6653.567518111307, 5924.585279030491, 6001.129836173752, 6024.09638554216, 6102.633841969496, 6207.534434917399, 5800.042992906166, 5842.853928651775, 5853.0, 5855.268091488564, 5861.560382971252, 12951.740965180737, 13102.80345794814, 25159.874200629063, 26385.0, 26398.472030559282, 2468.925932222018, 2573.3052075939577, 2575.085844525185, 2985.7163569461086, 3195.328980914011, 14215.218163001031, 15364.846351536453, 16132.838671613252, 16376.426825061184, 17044.744328835077, 1908.2235347569253, 1945.9124339404761, 2051.466618679139, 2053.37372101509, 2055.7122002919455, 116046.41976790062, 118143.4092829539, 121839.0, 130850.69149308576, 136994.0, 20009.499762505762, 21137.943102844798, 21299.574008519743, 22000.44998875041, 22444.88777556102, 35377.87732736044, 35504.467432988524, 35747.285054298765, 36099.81950090285, 36168.457473137925, 10533.736656583647, 10669.519871605797, 10811.0, 10965.232433729563, 11014.0, 8785.736427907173, 8843.292536597057, 8843.91156088431, 8926.955365223263, 8960.79029331045, 9646.324757266924, 9798.510074496247, 9800.264980126376, 9828.754281143028, 9884.011598840058, 3484.884836852183, 3653.6163702811355, 3854.807259637007, 3864.304425203434, 3886.7473614214987, 747.9155224923882, 786.8898354230412, 874.7534762962753, 926.6525053105086, 963.7108867339911, 9426.340156189139, 20340.69488957667, 21082.367528974155, 21145.0, 21634.459138521663, 365.554024090609, 418.35155508961594, 432.4831825968001, 443.025344242661, 450.69127647561527, 4662.790174442158, 5032.547070763649, 5277.287566178594, 5521.757604539027, 5881.882362352814, 245960.0, 248720.0, 248898.0, 517177.82822172047, 593220.0, 77174.68475945618, 82094.58952705318, 130667.34666326607, 135188.0, 135237.0, 434.19890302392037, 444.8887778055486, 447.88802799300174, 449.17576247586044, 449.43595787286745, 10108.545115469675, 10571.942805719367, 11071.72320691989, 11436.199466037298, 11644.941775291069, 48145.75927120482, 51344.0, 52450.47549524456, 52978.735106324224, 53178.73410632922, 64074.35925640684, 73000.26999729934, 75735.0, 76037.23962760302, 76252.0, 7498.137714162941, 8006.919930800617, 8010.799730006796, 8016.478928869605, 8076.0, 8179.918200817916, 12050.939745301568, 12079.214851035005, 12282.938585307016, 12308.569200077955, 8082.878756818534, 10648.627298044532, 10658.774240962079, 10776.67669969902, 11028.172887033345, 6677.697848919414, 8614.741557753277, 10179.898201017895, 10535.42055186972, 10713.142948564253, 10718.6065811444, 10800.405977671304, 10860.348379097279, 10863.0, 11176.161787866105, 8071.59642017885, 8093.392995525477, 8115.9855018122735, 8146.14465481116, 8171.713990010321, 9858.471936849799, 10258.794824103625, 10375.16998640122, 10527.052565269163, 11000.614978475714], "value": 9504.198479064797, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.6160798192466, 1510.3656464285002, 1696.4138530474008, 1767.6199617082325, 1823.0337920901918, 7487.625618719056, 7778.0, 8066.5966701664975, 8518.999999999993, 8534.487930724154, 8037.91962080379, 8319.251267385935, 8326.500409975406, 8422.19989101036, 8424.115467875878, 2329.8951547180377, 2349.3656712687566, 2351.670766092748, 2354.9411264718433, 2358.5518751437257, 2341.566810140121, 2369.668246445499, 2383.52329534093, 2392.1986134644917, 2394.162043284854, 2293.2776175504746, 2299.6665483504967, 2325.2907863101746, 2330.1611419888827, 2333.3349995251356, 2295.896684649195, 2297.9195728149516, 2314.2016004478514, 2319.385362878847, 2331.25399872041, 2327.476317828492, 2352.0826877517784, 2355.128602417117, 2356.8939397727145, 2359.044586942291, 6902.965485172592, 8391.202835730595, 8435.198656127657, 8473.322134229247, 8516.403851730382, 4775.641826863, 5017.89964200714, 5114.156164232897, 5199.532042116228, 5221.55616772571, 3405.642407547197, 3453.240287136826, 3496.790192588453, 3602.7117830573493, 3614.9277014459826, 861.3238607692931, 901.8872640919885, 971.4511301114848, 1014.4065721552901, 1117.3966058328492, 9490.861096668375, 10771.353718776898, 10859.78280434387, 10864.78270434587, 10966.725831854188, 4511.774411279423, 4596.977015114903, 4668.766561671903, 4673.929891051637, 4742.7865746040825, 7952.244536768998, 9334.766630834147, 9401.200897923616, 9467.479288639053, 9642.228621710246, 4357.215701173755, 5173.068847607392, 5175.922361164586, 5187.533122019036, 5199.532042116152, 3916.4516967624554, 4266.5093514246255, 4429.75636339998, 4595.172868883633, 4735.763211839394, 3778.6032466591164, 3833.811518429296, 3880.8447662093763, 3901.08324543732, 3908.413737939275, 1082.9079528240031, 1275.2603489975793, 1280.6606249343931, 1292.1149012926176, 1295.559509766684, 790.4506368074133, 863.8142799298181, 910.3354551177678, 918.4030380252889, 926.9536523173814, 5439.5104440600535, 5451.836444906659, 5453.890922181534, 5599.99200143978, 5636.408177141423, 924.8242833861544, 963.0706368354431, 982.886967998675, 989.4656885282011, 996.6163027234567, 3673.68773654237, 3909.3940439231965, 3966.286068507696, 4028.7582745035393, 4038.273110840018, 9895.30732848708, 10216.693499195037, 10231.181505479544, 10233.43716095607, 10248.180145588334, 9724.416535007922, 9868.358556693793, 10161.136303414292, 10182.18542516597, 10189.745256368502, 7464.888026679604, 7746.147923728498, 7769.300762931363, 7921.485103468256, 7924.801879953047, 5804.941950580609, 5892.410758924245, 5951.434613711691, 5989.371116032754, 6003.879922401615, 9215.953920230357, 9344.112309330603, 9434.433933963985, 9445.0, 9460.195883349992, 8988.146126118008, 9415.870095588572, 9422.528873556432, 9454.432734035705, 9477.336586438883, 6633.9336606633315, 6853.588784672736, 7009.1939426966555, 7170.784876453506, 7302.890456643261, 7795.298423141944, 8035.397345199017, 8037.718679846177, 8051.114377418362, 8058.597070146587, 7063.611501367269, 7241.746538871114, 7250.456215783732, 7271.636418179176, 7323.231060738546, 6919.238883722887, 7029.5430796998035, 7059.5764254144915, 7161.749338773118, 7178.676959536938, 6609.768658096944, 6621.106150669598, 6622.8344291393105, 6705.396514313735, 6712.597244165366, 4117.382392641128, 4834.540718631724, 4839.370881785346, 4895.412550493964, 4915.557599816034, 4186.602272784081, 4751.429828420612, 4874.878128046827, 4902.779374928065, 4989.950100498949], "value": 4419.2349060584775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.7610668984708, 301.14775186222994, 307.3530218889238, 311.1598683554402, 311.40366198729436, 4898.314236006958, 5087.694738315695, 5245.639462241997, 5314.840554783353, 5334.03987282289, 4253.680973926952, 4489.977550112253, 4917.057762183935, 5115.391150513237, 5181.6113791465605, 3770.8114594270314, 3926.8429262829454, 4033.475648165734, 4037.7375470594316, 4039.313316736147, 3350.6481819408978, 3748.0, 4045.8381664733524, 4051.9262395465225, 4056.553779084298, 3365.697087262146, 3545.503629491873, 3939.5666476687543, 3960.386140148282, 3978.4032395140816, 3610.801405922673, 3942.0, 3946.782926939017, 3953.5058117735284, 3963.286608410485, 2854.5290027145497, 2921.4595299869593, 3412.6861158453926, 3423.3324501722172, 3437.8968630941113, 4646.838290427393, 5068.4171320298265, 5085.0844849466075, 5118.283440318359, 5144.180845115404, 3859.55615104261, 4442.844500442501, 4454.507739907135, 4474.657602719171, 4502.549745025504, 5189.455107213726, 5462.852800911814, 5506.201600767866, 5508.3114610673665, 5526.778928842841, 3574.428091505373, 3688.5389326334207, 3738.1776009277914, 3761.247750449921, 3791.2796568652143, 5041.706023759218, 5720.342160651537, 5741.511971482429, 5748.160588611646, 5753.741081651336, 3047.7409420199533, 3788.9431658525145, 3797.8480860765812, 3821.84712611498, 3846.9230615388096, 3394.9490757638655, 3867.5358956925347, 3887.3002859485, 3899.922001559953, 3902.6097390261316, 4048.149888523443, 4325.545817689161, 4348.652107831366, 4476.052916981123, 4510.436754963607, 5087.491250874957, 5296.285001524822, 5578.665280083208, 5601.60788744784, 5647.745851436695, 1114.7603265297837, 1125.5666568371073, 1172.284906207213, 1202.6350092644736, 1204.9395533637403, 759.3659294489053, 761.46316846623, 805.3114587028124, 824.2952275804101, 863.1886027134474, 4094.61101195386, 4228.112096459717, 4543.7728113594185, 4795.712257264575, 5066.442691303953, 5206.791728330824, 5363.946360536423, 5389.461053894657, 5400.784823414779, 5497.752601132958, 4361.367601697736, 4500.909981800346, 4520.751358675305, 4893.559579637849, 5145.716985565829, 2333.742062186598, 2353.5881220786296, 2511.2717311979322, 2816.549601846407, 2855.4146399987953, 5080.348886611866, 5193.468441459768, 5387.434319396407, 5760.913586296209, 5776.57002417726, 4608.078384323148, 4711.293306004059, 5424.267723857308, 5559.694216818033, 5569.799929028011, 4651.95331050511, 5129.743512824344, 5153.871153221199, 5219.869503262373, 5250.849490305786, 5076.593872490118, 5406.567474601944, 5457.590680698884, 5475.370332411823, 5507.9449205507435, 4331.003869110059, 5164.32863727713, 5312.760925758273, 5359.651622644516, 5436.939796739598, 5738.741756621045, 5763.760791429695, 5767.971160144172, 5774.82675519735, 5826.7086645666695, 2968.9703102968692, 3035.694651299962, 3403.982980085084, 3536.1513236823494, 3646.9452958205115, 4829.855104346875, 5061.658660455056, 5366.919496207482, 5413.296271484839, 5436.429174936575, 4650.813967441264, 5269.947300527099, 5343.62594618373, 5469.097598896137, 5543.584231182596, 4460.353248778844, 5288.5985213917575, 5518.33779946409, 5531.2532808072, 5540.307461567304, 4828.010257897193, 4971.78755453834, 5092.414372347227, 5238.71187084714, 5395.78416863321, 4383.860196349036, 4452.5992660660695, 4743.928841067318, 5191.662541934762, 5208.401033881152, 4421.911561768682, 4897.877553061059, 5149.742512874266, 5483.958047970873, 5501.614886957875], "value": 3883.489478122319, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.2018238660767, 698.183125742881, 760.7337431898833, 761.756238003839, 792.6393490961611, 13982.230977296245, 14761.261936903165, 14789.0, 14796.6300842479, 14820.258987050658, 13077.869221307785, 13091.083624146313, 13116.34418279087, 13131.343432828367, 13173.934130329359, 615.0743131586967, 645.8611398549311, 784.5684873319651, 795.6658203554492, 812.0580127052591, 802.9156938521459, 808.8422757562278, 809.1786836361098, 809.2837838512929, 812.5733989655419, 849.6261644876265, 850.5917159763318, 854.0050840770494, 854.3122786157172, 855.2601999270618, 829.7884039569901, 834.0783434305066, 843.5782108945527, 845.6828689241565, 847.2078606502939, 620.4694985787178, 629.625372903122, 637.2894222941434, 637.977670781525, 639.2584601861864, 13388.866111338957, 13974.0, 13987.720245595134, 14010.789838152436, 14276.928615356857, 11351.545938162546, 13465.0, 13618.523351682643, 13739.587812365646, 14053.437862485489, 10741.0, 10752.301100428447, 10928.344299341985, 11188.776224475465, 11322.773544529064, 3517.419625761772, 3597.4244120940525, 3606.783592984429, 3613.2773445311045, 3728.0120767996505, 63413.68293158505, 83639.58180209121, 85321.0, 87349.0, 87561.12438875594, 9948.00519948014, 10491.632792852213, 10788.46057697112, 10849.620263290746, 10857.782844343226, 18873.62252754937, 19302.938338391243, 19399.515012124528, 19451.513712157026, 19472.902635487015, 6210.968945155245, 7350.08123984502, 7410.62946852666, 7488.513246638951, 7525.172231054579, 4177.728447650893, 4208.579142085767, 4291.699581029298, 4299.376590394375, 4305.31115021595, 3229.80621162731, 3249.951250731194, 3320.900372988814, 3332.3168750406107, 3340.0146956647845, 618.7957973868703, 618.9938100619026, 625.9530535209877, 628.3339659960457, 639.3542522052667, 606.2088973889029, 609.8810731907324, 611.6849822341497, 616.5356239825427, 625.3496363781707, 8407.95796021016, 8556.229939305367, 8610.13898610134, 8642.22220000203, 8648.827023459495, 506.94423613402495, 538.2813943385546, 585.338567418814, 622.4895585619839, 636.328673249726, 3456.395130852091, 3525.506429099928, 3601.333753255604, 4196.328587425997, 4222.092250166229, 72366.19084522928, 74402.62798685972, 110953.0, 116082.0, 120994.0, 65828.0, 67699.66150169217, 68961.65519172372, 93896.53051734834, 98568.50715746375, 63924.0, 73981.26018739743, 78144.60927695326, 85758.0, 87883.0, 9253.814923701622, 9411.0, 9702.854457183006, 9765.951170244387, 9772.26707996889, 41565.58434415738, 49643.0, 52547.423577291214, 54795.726021369635, 55114.0, 65149.674251628436, 73353.53292934004, 78099.60950195213, 80976.19023809687, 81437.0, 12045.81931271014, 12052.758944820878, 12068.75862482763, 12102.939485302517, 12478.438470268678, 13383.330833458484, 13386.129901556757, 13422.798658020332, 13572.389242484309, 13656.863431365558, 12579.182353147016, 12581.685457863627, 12708.682282943, 12849.229046257255, 13102.672433189247, 10551.419671918118, 11611.47748351343, 11829.822552661544, 12500.621204440678, 12788.872111278768, 11986.820197696867, 12126.211796233216, 12146.149769516402, 12370.195937264049, 12478.313692746986, 10221.846672299773, 10437.425941572983, 10723.410212438079, 10822.729431764268, 10929.0, 10607.575696972342, 11123.777524449304, 11130.443477825915, 11141.832872506757, 11162.832557511481], "value": 7005.399517322184, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 120.0, 118.0, 117.0, 119.0, 121.0, 120.0, 119.0, 121.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 126.0, 150.0, 182.0, 119.0, 110.0, 111.0, 123.0, 166.0, 104.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 239.0, 275.0, 207.0, 194.0, 199.0, 194.0, 218.0, 194.0, 195.0], "value": 212.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 93.0, 80.0, 93.0, 60.0, 59.0, 68.0, 49.0, 90.0, 49.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 96.0, 88.0, 100.0, 86.0, 85.0, 70.0, 70.0, 89.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 117.0, 85.0, 127.0, 120.0, 89.0, 89.0, 89.0, 86.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 107.0, 143.0, 95.0, 96.0, 155.0, 153.0, 104.0, 154.0, 105.0], "value": 120.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 138.0, 135.0, 86.0, 90.0, 73.0, 132.0, 142.0, 136.0, 89.0], "value": 110.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 60.0, 60.0, 61.0, 59.0, 59.0, 60.0, 60.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 89.0, 79.0, 108.0, 70.0, 71.0, 72.0, 76.0, 73.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 92.0, 92.0, 105.0, 94.0, 87.0, 83.0, 85.0, 86.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 161.0, 154.0, 159.0, 165.0, 153.0, 163.0, 165.0, 206.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 182.0, 167.0, 228.0, 200.0, 182.0, 166.0, 166.0, 165.0, 170.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 67.0, 69.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 68.0, "unit": "score"}]}]} 09:23:34 INFO - Return code: 0 09:23:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:34 ERROR - # TBPL SUCCESS # 09:23:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:34 INFO - 2017-01-04 09:23:34.359 screenresolution[1654:10759] starting screenresolution argv=screenresolution get 09:23:34 INFO - 2017-01-04 09:23:34.362 screenresolution[1654:10759] Display 0: 1600x1200x32@60 09:23:34 INFO - 2017-01-04 09:23:34.368 screenresolution[1655:10762] starting screenresolution argv=screenresolution list 09:23:34 INFO - Available Modes on Display 0 09:23:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:34 INFO - Intel Iris: 09:23:34 INFO - Chipset Model: Intel Iris 09:23:34 INFO - Type: GPU 09:23:34 INFO - Bus: Built-In 09:23:34 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:34 INFO - Vendor: Intel (0x8086) 09:23:34 INFO - Device ID: 0x0a2e 09:23:34 INFO - Revision ID: 0x0009 09:23:34 INFO - Displays: 09:23:34 INFO - Display: 09:23:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:34 INFO - Display Serial Number: 1600x1200 60 09:23:34 INFO - Main Display: Yes 09:23:34 INFO - Mirror: Off 09:23:34 INFO - Online: Yes 09:23:34 INFO - Rotation: Supported 09:23:34 INFO - Return code: 0 09:23:34 INFO - Running post-action listener: _resource_record_post_action 09:23:34 INFO - [mozharness: 2017-01-04 17:23:34.615381Z] Finished run-tests step (success) 09:23:34 INFO - Running post-run listener: _resource_record_post_run 09:23:34 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 38734336; Write bytes: 655366144; Read time: 251; Write time: 1880 09:23:34 INFO - TinderboxPrint: CPU usage
54.6% 09:23:34 INFO - TinderboxPrint: I/O read bytes / time
38,734,336 / 251 09:23:34 INFO - TinderboxPrint: I/O write bytes / time
655,366,144 / 1,880 09:23:34 INFO - TinderboxPrint: CPU idle
2,197.2 (45.4%) 09:23:34 INFO - TinderboxPrint: CPU system
983.3 (20.3%) 09:23:34 INFO - TinderboxPrint: CPU user
1,657.3 (34.3%) 09:23:34 INFO - TinderboxPrint: Swap in / out
388,096,000 / 0 09:23:34 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346274304; Write bytes: 345671680; Read time: 27060; Write time: 697 09:23:34 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36633088; Write bytes: 302546944; Read time: 233; Write time: 1153 09:23:35 INFO - Running post-run listener: _upload_blobber_files 09:23:35 INFO - Blob upload gear active. 09:23:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:35 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 09:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:36 INFO - (blobuploader) - INFO - Done attempting. 09:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:37 INFO - (blobuploader) - INFO - Done attempting. 09:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:39 INFO - (blobuploader) - INFO - Done attempting. 09:23:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:39 INFO - Return code: 0 09:23:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab6f0b62afe29b3c73517a8a155e0df8976af4dba3cb2c53587e04a3ce69716bb5712f850796bb8f627659c14faf6e1fbf10b402f270936db9db70ba5937684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c368dbfc0bf90772ebc7525bb43649d2a34e37ca8d7940a8f119f7ff3dbed5574eaacc5c30b7ac29e6f20d9fef858219a7cd60a1f923e2dc607e930c3990599a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5b00a0cf427ca9e283112f1fd5b462fa4a7c7d03e006c3a722422ef5ac1b727c83da1477fd8c6740830e611941fe7f9101a451144ced94f679a84353c3f97d2"} 09:23:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:39 INFO - Contents: 09:23:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab6f0b62afe29b3c73517a8a155e0df8976af4dba3cb2c53587e04a3ce69716bb5712f850796bb8f627659c14faf6e1fbf10b402f270936db9db70ba5937684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c368dbfc0bf90772ebc7525bb43649d2a34e37ca8d7940a8f119f7ff3dbed5574eaacc5c30b7ac29e6f20d9fef858219a7cd60a1f923e2dc607e930c3990599a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5b00a0cf427ca9e283112f1fd5b462fa4a7c7d03e006c3a722422ef5ac1b727c83da1477fd8c6740830e611941fe7f9101a451144ced94f679a84353c3f97d2"} 09:23:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:39 INFO - Copying logs to upload dir... 09:23:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.691370 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-04 09:23:39.861938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:23:39.866977) ========= 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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/Mozilla-Aurora/sha512/5ab6f0b62afe29b3c73517a8a155e0df8976af4dba3cb2c53587e04a3ce69716bb5712f850796bb8f627659c14faf6e1fbf10b402f270936db9db70ba5937684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c368dbfc0bf90772ebc7525bb43649d2a34e37ca8d7940a8f119f7ff3dbed5574eaacc5c30b7ac29e6f20d9fef858219a7cd60a1f923e2dc607e930c3990599a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5b00a0cf427ca9e283112f1fd5b462fa4a7c7d03e006c3a722422ef5ac1b727c83da1477fd8c6740830e611941fe7f9101a451144ced94f679a84353c3f97d2"} build_url:https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ab6f0b62afe29b3c73517a8a155e0df8976af4dba3cb2c53587e04a3ce69716bb5712f850796bb8f627659c14faf6e1fbf10b402f270936db9db70ba5937684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c368dbfc0bf90772ebc7525bb43649d2a34e37ca8d7940a8f119f7ff3dbed5574eaacc5c30b7ac29e6f20d9fef858219a7cd60a1f923e2dc607e930c3990599a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5b00a0cf427ca9e283112f1fd5b462fa4a7c7d03e006c3a722422ef5ac1b727c83da1477fd8c6740830e611941fe7f9101a451144ced94f679a84353c3f97d2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:23:39.905647) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:23:39.906016) ========= 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.ziEb6Jh8ck/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.4cvEhvRY5R/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005216 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:23:39.954450) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:23:39.957846) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:23:39.958236) ========= ========= Total master_lag: 0.70 =========