builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0143 starttime: 1481019856.78 results: success (0) buildid: 20161206004003 builduid: f27165c7e62d4db5aa67d2c80ad34387 revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:16.779811) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:16.780289) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:16.780621) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:16.805257) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:16.805705) ========= 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.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-06 02:24:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 215M=0s 2016-12-06 02:24:17 (215 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.695606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:17.525116) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:17.525462) ========= 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.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.020351 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:17.576127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 02:24:17.576486) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46049b21980d67b13d6915c052a3025c273a1788 --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 46049b21980d67b13d6915c052a3025c273a1788 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-06 02:24:17,655 truncating revision to first 12 chars 2016-12-06 02:24:17,655 Setting DEBUG logging. 2016-12-06 02:24:17,655 attempt 1/10 2016-12-06 02:24:17,655 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46049b21980d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 02:24:19,225 unpacking tar archive at: mozilla-aurora-46049b21980d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.806789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 02:24:19.405070) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:19.405393) ========= script_repo_revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:19.405837) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:19.406144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:19.426189) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-06 02:24:19.426598) ========= /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.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:19 INFO - MultiFileLogger online at 20161206 02:24:19 in /builds/slave/test 02:24:19 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 02:24:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:19 INFO - {'append_to_log': False, 02:24:19 INFO - 'base_work_dir': '/builds/slave/test', 02:24:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:24:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:19 INFO - 'branch': 'Mozilla-Aurora', 02:24:19 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:19 INFO - 'config_files': ('talos/mac_config.py',), 02:24:19 INFO - 'default_actions': ('clobber', 02:24:19 INFO - 'read-buildbot-config', 02:24:19 INFO - 'download-and-extract', 02:24:19 INFO - 'populate-webroot', 02:24:19 INFO - 'create-virtualenv', 02:24:19 INFO - 'install', 02:24:19 INFO - 'run-tests'), 02:24:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:19 INFO - 'download_minidump_stackwalk': True, 02:24:19 INFO - 'download_symbols': 'ondemand', 02:24:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:19 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:19 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:19 INFO - 'installer_path': 'installer.exe', 02:24:19 INFO - 'log_level': 'info', 02:24:19 INFO - 'log_name': 'talos', 02:24:19 INFO - 'log_to_console': True, 02:24:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:24:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:24:19 INFO - 'opt_config_files': (), 02:24:19 INFO - 'pip_index': False, 02:24:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:19 INFO - 'cmd': ('bash', 02:24:19 INFO - '-c', 02:24:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:19 INFO - 'enabled': True, 02:24:19 INFO - 'halt_on_failure': False, 02:24:19 INFO - 'name': 'check_screen_resolution'},), 02:24:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:19 INFO - 'cmd': ('bash', 02:24:19 INFO - '-c', 02:24:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:19 INFO - 'enabled': True, 02:24:19 INFO - 'halt_on_failure': False, 02:24:19 INFO - 'name': 'check_screen_resolution'},), 02:24:19 INFO - 'run_cmd_checks_enabled': True, 02:24:19 INFO - 'sps_profile': False, 02:24:19 INFO - 'sps_profile_interval': 0, 02:24:19 INFO - 'suite': 'dromaeojs-e10s', 02:24:19 INFO - 'system_bits': '32', 02:24:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:19 INFO - 'title': 't-yosemite-r7-0143', 02:24:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:19 INFO - 'use_talos_json': True, 02:24:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:19 INFO - 'work_dir': 'build'} 02:24:19 INFO - [mozharness: 2016-12-06 10:24:19.635089Z] Running clobber step. 02:24:19 INFO - Running pre-action listener: _resource_record_pre_action 02:24:19 INFO - Running main action method: clobber 02:24:19 INFO - rmtree: /builds/slave/test/build 02:24:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:24:21 INFO - Running post-action listener: _resource_record_post_action 02:24:21 INFO - [mozharness: 2016-12-06 10:24:21.725471Z] Finished clobber step (success) 02:24:21 INFO - [mozharness: 2016-12-06 10:24:21.725592Z] Running read-buildbot-config step. 02:24:21 INFO - Running pre-action listener: _resource_record_pre_action 02:24:21 INFO - Running main action method: read_buildbot_config 02:24:21 INFO - Using buildbot properties: 02:24:21 INFO - { 02:24:21 INFO - "project": "", 02:24:21 INFO - "product": "firefox", 02:24:21 INFO - "script_repo_revision": "production", 02:24:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:24:21 INFO - "repository": "", 02:24:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:24:21 INFO - "buildid": "20161206004003", 02:24:21 INFO - "stage_platform": "macosx64", 02:24:21 INFO - "basedir": "/builds/slave/test", 02:24:21 INFO - "nightly_build": "True", 02:24:21 INFO - "buildnumber": 12, 02:24:21 INFO - "slavename": "t-yosemite-r7-0143", 02:24:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:24:21 INFO - "platform": "yosemite_r7", 02:24:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:24:21 INFO - "branch": "mozilla-aurora", 02:24:21 INFO - "repo_path": "releases/mozilla-aurora", 02:24:21 INFO - "revision": "46049b21980d67b13d6915c052a3025c273a1788", 02:24:21 INFO - "pgo_build": "False", 02:24:21 INFO - "builduid": "f27165c7e62d4db5aa67d2c80ad34387", 02:24:21 INFO - "slavebuilddir": "test" 02:24:21 INFO - } 02:24:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:24:21 INFO - Running post-action listener: _resource_record_post_action 02:24:21 INFO - [mozharness: 2016-12-06 10:24:21.728907Z] Finished read-buildbot-config step (success) 02:24:21 INFO - [mozharness: 2016-12-06 10:24:21.729010Z] Running download-and-extract step. 02:24:21 INFO - Running pre-action listener: _resource_record_pre_action 02:24:21 INFO - Running main action method: download_and_extract 02:24:21 INFO - mkdir: /builds/slave/test/build/tests 02:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:21 INFO - trying https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:24:21 INFO - Downloading https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/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 02:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/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 02:24:22 INFO - Downloaded 1199 bytes. 02:24:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:24:22 INFO - Using the following test package requirements: 02:24:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:24:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:24:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:24:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'jsshell-mac.zip'], 02:24:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:24:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:24:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:24:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:24:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:24:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:24:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:24:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:24:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:24:22 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:24:22 INFO - Http code: 200 02:24:22 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 02:24:22 INFO - x-amz-cf-id: 1tbcUwdHQeEPBNNTghDpJfpd8YXqKI9fkaIG_xuDRJq18ERnyTeBug== 02:24:22 INFO - x-amz-version-id: p1F22bvmTnRMsWAK8jDvZ8phR8gafu03 02:24:23 INFO - Expected file size: 16282014 02:24:23 INFO - Obtained file size: 16282014 02:24:29 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 02:24:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:24:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:24:29 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:24:30 INFO - Http code: 200 02:24:30 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 02:24:30 INFO - x-amz-cf-id: RSHrDloy8VYcJSViEni94Rzbu3tBWlDHLNDSBMTkFmMipZstRRZczQ== 02:24:30 INFO - x-amz-version-id: p1F22bvmTnRMsWAK8jDvZ8phR8gafu03 02:24:30 INFO - Expected file size: 16282014 02:24:30 INFO - Obtained file size: 16282014 02:24:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:24:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:24:36 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:24:37 INFO - Http code: 200 02:24:37 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 02:24:37 INFO - x-amz-cf-id: p3yTlNeUanZ4LsxmIEcAxqVlO0Tzyc7dvMa9-AVCxvgk-eOpcNzOqw== 02:24:37 INFO - x-amz-version-id: Eevi5E7gG9YrlgAPXsY6hZrthaoUChRY 02:24:37 INFO - Expected file size: 14409038 02:24:37 INFO - Obtained file size: 14409038 02:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:38 INFO - trying https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:38 INFO - Downloading https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/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 02:24:40 INFO - Downloaded 109857732 bytes. 02:24:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:40 INFO - mkdir: /builds/slave/test/properties 02:24:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:24:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:24:40 INFO - Contents: 02:24:40 INFO - build_url:https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:41 INFO - Running post-action listener: _resource_record_post_action 02:24:41 INFO - Running post-action listener: set_extra_try_arguments 02:24:41 INFO - [mozharness: 2016-12-06 10:24:41.647417Z] Finished download-and-extract step (success) 02:24:41 INFO - [mozharness: 2016-12-06 10:24:41.647547Z] Running populate-webroot step. 02:24:41 INFO - Running pre-action listener: _resource_record_pre_action 02:24:41 INFO - Running main action method: populate_webroot 02:24:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:24:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:24:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:24:41 INFO - '--tppagecycles', 02:24:41 INFO - '7'], 02:24:41 INFO - 'tests': ['tsvgm']}}, 02:24:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:24:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:24:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:24:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:24:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['damp', 'tps']}, 02:24:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'tests': ['damp', 'tps']}, 02:24:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['dromaeo_dom']}, 02:24:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:24:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['basic_compositor_video']}, 02:24:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:24:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['a11yr', 02:24:41 INFO - 'ts_paint', 02:24:41 INFO - 'tpaint', 02:24:41 INFO - 'sessionrestore', 02:24:41 INFO - 'sessionrestore_no_auto_restore', 02:24:41 INFO - 'tabpaint']}, 02:24:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['tsvgx', 02:24:41 INFO - 'tsvgr_opacity', 02:24:41 INFO - 'tart', 02:24:41 INFO - 'tscrollx', 02:24:41 INFO - 'cart']}, 02:24:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:24:41 INFO - 'tsvgr_opacity', 02:24:41 INFO - 'tart', 02:24:41 INFO - 'tscrollx', 02:24:41 INFO - 'cart']}, 02:24:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'talos_options': ['--disable-e10s'], 02:24:41 INFO - 'tests': ['tp5o']}, 02:24:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'tests': ['tp5o']}, 02:24:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'talos_options': ['--disable-e10s', 02:24:41 INFO - '--xperf_path', 02:24:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:41 INFO - 'tests': ['tp5n']}, 02:24:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:41 INFO - 'talos_options': ['--xperf_path', 02:24:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:41 INFO - 'tests': ['tp5n']}}, 02:24:41 INFO - 'talos.zip': {'path': '', 02:24:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:24:41 INFO - Running post-action listener: _resource_record_post_action 02:24:41 INFO - [mozharness: 2016-12-06 10:24:41.666643Z] Finished populate-webroot step (success) 02:24:41 INFO - [mozharness: 2016-12-06 10:24:41.666775Z] Running create-virtualenv step. 02:24:41 INFO - Running pre-action listener: _resource_record_pre_action 02:24:41 INFO - Running main action method: create_virtualenv 02:24:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:24:41 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 02:24:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:24:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:24:41 INFO - Using real prefix '/tools/python27' 02:24:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:24:42 INFO - Installing distribute.............................................................................................................................................................................................done. 02:24:45 INFO - Installing pip.................done. 02:24:45 INFO - Return code: 0 02:24:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:24:45 INFO - Reading from file tmpfile_stdout 02:24:45 INFO - Output received: 02:24:45 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) 02:24:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:45 INFO - 'HOME': '/Users/cltbld', 02:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:45 INFO - 'LOGNAME': 'cltbld', 02:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:45 INFO - 'MOZ_NO_REMOTE': '1', 02:24:45 INFO - 'NO_EM_RESTART': '1', 02:24:45 INFO - 'PAGER': '/bin/cat', 02:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:45 INFO - 'PWD': '/builds/slave/test', 02:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:45 INFO - 'SHELL': '/bin/bash', 02:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:45 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:45 INFO - 'USER': 'cltbld', 02:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:45 INFO - 'XPC_FLAGS': '0x0', 02:24:45 INFO - 'XPC_SERVICE_NAME': '0', 02:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:45 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 02:24:45 INFO - Cleaning up... 02:24:45 INFO - Return code: 0 02:24:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:45 INFO - 'HOME': '/Users/cltbld', 02:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:45 INFO - 'LOGNAME': 'cltbld', 02:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:45 INFO - 'MOZ_NO_REMOTE': '1', 02:24:45 INFO - 'NO_EM_RESTART': '1', 02:24:45 INFO - 'PAGER': '/bin/cat', 02:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:45 INFO - 'PWD': '/builds/slave/test', 02:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:45 INFO - 'SHELL': '/bin/bash', 02:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:45 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:45 INFO - 'USER': 'cltbld', 02:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:45 INFO - 'XPC_FLAGS': '0x0', 02:24:45 INFO - 'XPC_SERVICE_NAME': '0', 02:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:46 INFO - Downloading/unpacking psutil>=3.1.1 02:24:46 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 02:24:46 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 02:24:46 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 02:24:46 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 02:24:46 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 02:24:46 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 02:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:24:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:50 INFO - Installing collected packages: psutil 02:24:50 INFO - Running setup.py install for psutil 02:24:50 INFO - building 'psutil._psutil_osx' extension 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 INFO - building 'psutil._psutil_posix' extension 02:24:50 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 02:24:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:24:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:24:50 INFO - ^ 02:24:50 INFO - 1 warning generated. 02:24:50 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 02:24:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:50 INFO - Successfully installed psutil 02:24:50 INFO - Cleaning up... 02:24:50 INFO - Return code: 0 02:24:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:24:50 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')]} 02:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:50 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')]} 02:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:50 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:50 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 02:24:50 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 02:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:50 INFO - 'HOME': '/Users/cltbld', 02:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:50 INFO - 'LOGNAME': 'cltbld', 02:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:50 INFO - 'MOZ_NO_REMOTE': '1', 02:24:50 INFO - 'NO_EM_RESTART': '1', 02:24:50 INFO - 'PAGER': '/bin/cat', 02:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:50 INFO - 'PWD': '/builds/slave/test', 02:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:50 INFO - 'SHELL': '/bin/bash', 02:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:50 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:50 INFO - 'USER': 'cltbld', 02:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:50 INFO - 'XPC_FLAGS': '0x0', 02:24:50 INFO - 'XPC_SERVICE_NAME': '0', 02:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:24:53 INFO - Installing collected packages: mozsystemmonitor 02:24:53 INFO - Running setup.py install for mozsystemmonitor 02:24:53 INFO - Successfully installed mozsystemmonitor 02:24:53 INFO - Cleaning up... 02:24:54 INFO - Return code: 0 02:24:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:54 INFO - 'HOME': '/Users/cltbld', 02:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:54 INFO - 'LOGNAME': 'cltbld', 02:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:54 INFO - 'MOZ_NO_REMOTE': '1', 02:24:54 INFO - 'NO_EM_RESTART': '1', 02:24:54 INFO - 'PAGER': '/bin/cat', 02:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:54 INFO - 'PWD': '/builds/slave/test', 02:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:54 INFO - 'SHELL': '/bin/bash', 02:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:54 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:54 INFO - 'USER': 'cltbld', 02:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:54 INFO - 'XPC_FLAGS': '0x0', 02:24:54 INFO - 'XPC_SERVICE_NAME': '0', 02:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:24:54 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 02:24:54 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 02:24:54 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 02:24:54 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 02:24:54 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 02:24:54 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 02:24:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:24:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:24:57 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 02:24:57 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 02:24:57 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 02:24:57 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 02:24:57 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 02:24:57 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 02:24:57 INFO - Downloading functools32-3.2.3-2.tar.gz 02:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:24:57 INFO - warning: no files found matching '*.txt' 02:24:57 INFO - no previously-included directories found matching 'build' 02:24:57 INFO - no previously-included directories found matching 'dist' 02:24:57 INFO - no previously-included directories found matching '.git*' 02:24:57 INFO - Installing collected packages: jsonschema, functools32 02:24:57 INFO - Running setup.py install for functools32 02:24:57 INFO - warning: no files found matching '*.txt' 02:24:57 INFO - no previously-included directories found matching 'build' 02:24:57 INFO - no previously-included directories found matching 'dist' 02:24:57 INFO - no previously-included directories found matching '.git*' 02:24:57 INFO - Successfully installed jsonschema functools32 02:24:57 INFO - Cleaning up... 02:24:57 INFO - Return code: 0 02:24:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:24:57 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')]} 02:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:57 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')]} 02:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:57 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:57 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 02:24:57 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 02:24:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:57 INFO - 'HOME': '/Users/cltbld', 02:24:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:57 INFO - 'LOGNAME': 'cltbld', 02:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:57 INFO - 'MOZ_NO_REMOTE': '1', 02:24:57 INFO - 'NO_EM_RESTART': '1', 02:24:57 INFO - 'PAGER': '/bin/cat', 02:24:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:57 INFO - 'PWD': '/builds/slave/test', 02:24:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:57 INFO - 'SHELL': '/bin/bash', 02:24:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:57 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:57 INFO - 'USER': 'cltbld', 02:24:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:57 INFO - 'XPC_FLAGS': '0x0', 02:24:57 INFO - 'XPC_SERVICE_NAME': '0', 02:24:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:24:58 INFO - Cleaning up... 02:24:58 INFO - Return code: 0 02:24:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:24:58 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')]} 02:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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')]} 02:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:24:58 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 02:24:58 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 02:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:58 INFO - 'HOME': '/Users/cltbld', 02:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:58 INFO - 'LOGNAME': 'cltbld', 02:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:58 INFO - 'MOZ_NO_REMOTE': '1', 02:24:58 INFO - 'NO_EM_RESTART': '1', 02:24:58 INFO - 'PAGER': '/bin/cat', 02:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:58 INFO - 'PWD': '/builds/slave/test', 02:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:58 INFO - 'SHELL': '/bin/bash', 02:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:24:58 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:58 INFO - 'USER': 'cltbld', 02:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:58 INFO - 'XPC_FLAGS': '0x0', 02:24:58 INFO - 'XPC_SERVICE_NAME': '0', 02:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:25:00 INFO - Downloading blobuploader-1.2.4.tar.gz 02:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:25:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:25:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:02 INFO - Downloading docopt-0.6.1.tar.gz 02:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:25:02 INFO - Installing collected packages: blobuploader, requests, docopt 02:25:02 INFO - Running setup.py install for blobuploader 02:25:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:25:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:25:02 INFO - Running setup.py install for requests 02:25:02 INFO - Running setup.py install for docopt 02:25:03 INFO - Successfully installed blobuploader requests docopt 02:25:03 INFO - Cleaning up... 02:25:03 INFO - Return code: 0 02:25:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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')]} 02:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:03 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:25:03 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 02:25:03 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 02:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:03 INFO - 'HOME': '/Users/cltbld', 02:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:03 INFO - 'LOGNAME': 'cltbld', 02:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:03 INFO - 'MOZ_NO_REMOTE': '1', 02:25:03 INFO - 'NO_EM_RESTART': '1', 02:25:03 INFO - 'PAGER': '/bin/cat', 02:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:03 INFO - 'PWD': '/builds/slave/test', 02:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:03 INFO - 'SHELL': '/bin/bash', 02:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:25:03 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:03 INFO - 'USER': 'cltbld', 02:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:03 INFO - 'XPC_FLAGS': '0x0', 02:25:03 INFO - 'XPC_SERVICE_NAME': '0', 02:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-w3oO89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-_S37FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ieggAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-muBFVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ooRMf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:25:03 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-7yn9sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-aAVzpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-QIWP7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-xjCbu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-LTVKm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-dLMExg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-t3nmJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ZA02Fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ZIjCgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:25:04 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-IfoYSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:25:05 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ReP7lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:25:05 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-3HWcvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:25:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:25:05 INFO - Running setup.py install for manifestparser 02:25:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:25:05 INFO - Running setup.py install for mozcrash 02:25:05 INFO - Running setup.py install for mozdebug 02:25:05 INFO - Running setup.py install for mozdevice 02:25:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:25:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:25:05 INFO - Running setup.py install for mozfile 02:25:06 INFO - Running setup.py install for mozhttpd 02:25:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Running setup.py install for mozinfo 02:25:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Running setup.py install for mozInstall 02:25:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Running setup.py install for mozleak 02:25:06 INFO - Running setup.py install for mozlog 02:25:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Running setup.py install for moznetwork 02:25:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:25:06 INFO - Running setup.py install for mozprocess 02:25:07 INFO - Running setup.py install for mozprofile 02:25:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:25:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:25:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:25:07 INFO - Running setup.py install for mozrunner 02:25:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:25:07 INFO - Running setup.py install for mozscreenshot 02:25:07 INFO - Running setup.py install for moztest 02:25:07 INFO - Running setup.py install for mozversion 02:25:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:25:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:25:07 INFO - Cleaning up... 02:25:07 INFO - Return code: 0 02:25:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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')]} 02:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:07 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:25:07 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 02:25:07 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 02:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:07 INFO - 'HOME': '/Users/cltbld', 02:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:07 INFO - 'LOGNAME': 'cltbld', 02:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:07 INFO - 'MOZ_NO_REMOTE': '1', 02:25:07 INFO - 'NO_EM_RESTART': '1', 02:25:07 INFO - 'PAGER': '/bin/cat', 02:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:07 INFO - 'PWD': '/builds/slave/test', 02:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:07 INFO - 'SHELL': '/bin/bash', 02:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:25:07 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:07 INFO - 'USER': 'cltbld', 02:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:07 INFO - 'XPC_FLAGS': '0x0', 02:25:07 INFO - 'XPC_SERVICE_NAME': '0', 02:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-7rotiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Wxpp1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8UKFuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-scXj2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-rHCTLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MmMgfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ILGj7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Ok5sD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:25:08 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)) 02:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:25:08 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8vk3Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-4zRrzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-yWE2g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-wIBKNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MEdW5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-1ETYVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-KoCO8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:25:09 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)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-SEg3H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:25:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:25:09 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-QRaw_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:25:09 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)) 02:25:09 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)) 02:25:09 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)) 02:25:09 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)) 02:25:09 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)) 02:25:09 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)) 02:25:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:25:09 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 02:25:09 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 02:25:09 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 02:25:09 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 02:25:09 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 02:25:09 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 02:25:12 INFO - Downloading blessings-1.6.tar.gz 02:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:25:12 INFO - Installing collected packages: blessings 02:25:12 INFO - Running setup.py install for blessings 02:25:12 INFO - Successfully installed blessings 02:25:12 INFO - Cleaning up... 02:25:12 INFO - Return code: 0 02:25:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:25:13 INFO - Reading from file tmpfile_stdout 02:25:13 INFO - Current package versions: 02:25:13 INFO - blessings == 1.6 02:25:13 INFO - blobuploader == 1.2.4 02:25:13 INFO - docopt == 0.6.1 02:25:13 INFO - functools32 == 3.2.3-2 02:25:13 INFO - jsonschema == 2.5.1 02:25:13 INFO - manifestparser == 1.1 02:25:13 INFO - mozInstall == 1.12 02:25:13 INFO - mozcrash == 1.0 02:25:13 INFO - mozdebug == 0.1 02:25:13 INFO - mozdevice == 0.48 02:25:13 INFO - mozfile == 1.2 02:25:13 INFO - mozhttpd == 0.7 02:25:13 INFO - mozinfo == 0.9 02:25:13 INFO - mozleak == 0.1 02:25:13 INFO - mozlog == 3.4 02:25:13 INFO - moznetwork == 0.27 02:25:13 INFO - mozprocess == 0.23 02:25:13 INFO - mozprofile == 0.28 02:25:13 INFO - mozrunner == 6.13 02:25:13 INFO - mozscreenshot == 0.1 02:25:13 INFO - mozsystemmonitor == 0.3 02:25:13 INFO - moztest == 0.7 02:25:13 INFO - mozversion == 1.4 02:25:13 INFO - psutil == 3.1.1 02:25:13 INFO - requests == 1.2.3 02:25:13 INFO - wsgiref == 0.1.2 02:25:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:13 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 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:25:13 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 02:25:13 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 02:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:13 INFO - 'HOME': '/Users/cltbld', 02:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:13 INFO - 'LOGNAME': 'cltbld', 02:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:13 INFO - 'MOZ_NO_REMOTE': '1', 02:25:13 INFO - 'NO_EM_RESTART': '1', 02:25:13 INFO - 'PAGER': '/bin/cat', 02:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:13 INFO - 'PWD': '/builds/slave/test', 02:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:13 INFO - 'SHELL': '/bin/bash', 02:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:25:13 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:13 INFO - 'USER': 'cltbld', 02:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:13 INFO - 'XPC_FLAGS': '0x0', 02:25:13 INFO - 'XPC_SERVICE_NAME': '0', 02:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 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)) 02:25:13 INFO - Cleaning up... 02:25:13 INFO - Return code: 0 02:25:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d75490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50bca0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, '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.tE5wERhgxU/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.mLFlL20FzY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:25:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:13 INFO - 'HOME': '/Users/cltbld', 02:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:13 INFO - 'LOGNAME': 'cltbld', 02:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:13 INFO - 'MOZ_NO_REMOTE': '1', 02:25:13 INFO - 'NO_EM_RESTART': '1', 02:25:13 INFO - 'PAGER': '/bin/cat', 02:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:13 INFO - 'PWD': '/builds/slave/test', 02:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:13 INFO - 'SHELL': '/bin/bash', 02:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:25:13 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:13 INFO - 'USER': 'cltbld', 02:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:13 INFO - 'XPC_FLAGS': '0x0', 02:25:13 INFO - 'XPC_SERVICE_NAME': '0', 02:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:25:13 INFO - Cleaning up... 02:25:13 INFO - Return code: 0 02:25:13 INFO - Running post-action listener: _resource_record_post_action 02:25:13 INFO - Running post-action listener: _start_resource_monitoring 02:25:13 INFO - Starting resource monitoring. 02:25:13 INFO - [mozharness: 2016-12-06 10:25:13.661092Z] Finished create-virtualenv step (success) 02:25:13 INFO - [mozharness: 2016-12-06 10:25:13.662115Z] Running install step. 02:25:13 INFO - Running pre-action listener: _resource_record_pre_action 02:25:13 INFO - Running main action method: install 02:25:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:25:13 INFO - Reading from file tmpfile_stdout 02:25:13 INFO - Detecting whether we're running mozinstall >=1.0... 02:25:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:25:14 INFO - Reading from file tmpfile_stdout 02:25:14 INFO - Output received: 02:25:14 INFO - Usage: mozinstall [options] installer 02:25:14 INFO - Options: 02:25:14 INFO - -h, --help show this help message and exit 02:25:14 INFO - -d DEST, --destination=DEST 02:25:14 INFO - Directory to install application into. [default: 02:25:14 INFO - "/builds/slave/test"] 02:25:14 INFO - --app=APP Application being installed. [default: firefox] 02:25:14 INFO - mkdir: /builds/slave/test/build/application 02:25:14 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'] 02:25:14 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 02:25:45 INFO - Reading from file tmpfile_stdout 02:25:45 INFO - Output received: 02:25:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:25:45 INFO - Running post-action listener: _resource_record_post_action 02:25:45 INFO - [mozharness: 2016-12-06 10:25:45.801245Z] Finished install step (success) 02:25:45 INFO - [mozharness: 2016-12-06 10:25:45.801406Z] Running run-tests step. 02:25:45 INFO - Running pre-action listener: _resource_record_pre_action 02:25:45 INFO - Running main action method: run_tests 02:25:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:25:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:25:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:25:45 INFO - 2016-12-06 02:25:45.815 screenresolution[2527:36865] starting screenresolution argv=screenresolution get 02:25:45 INFO - 2016-12-06 02:25:45.818 screenresolution[2527:36865] Display 0: 1600x1200x32@60 02:25:45 INFO - 2016-12-06 02:25:45.824 screenresolution[2528:36867] starting screenresolution argv=screenresolution list 02:25:45 INFO - Available Modes on Display 0 02:25:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:25:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:25:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:25:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:25:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:25:46 INFO - Intel Iris: 02:25:46 INFO - Chipset Model: Intel Iris 02:25:46 INFO - Type: GPU 02:25:46 INFO - Bus: Built-In 02:25:46 INFO - VRAM (Dynamic, Max): 1536 MB 02:25:46 INFO - Vendor: Intel (0x8086) 02:25:46 INFO - Device ID: 0x0a2e 02:25:46 INFO - Revision ID: 0x0009 02:25:46 INFO - Displays: 02:25:46 INFO - Display: 02:25:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:25:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:25:46 INFO - Display Serial Number: 1600x1200 60 02:25:46 INFO - Main Display: Yes 02:25:46 INFO - Mirror: Off 02:25:46 INFO - Online: Yes 02:25:46 INFO - Rotation: Supported 02:25:46 INFO - Return code: 0 02:25:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:25:46 INFO - Python 2.7.3 02:25:46 INFO - Return code: 0 02:25:46 INFO - grabbing minidump binary from tooltool 02:25:46 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')]} 02:25:46 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 0x101b89d30>, '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 0x1019efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b81b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:25:46 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 02:25:46 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 02:25:46 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 02:25:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:25:46 INFO - Return code: 0 02:25:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:25:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:25:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:25:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:25:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:25:46 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/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:25:46 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/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0143 --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 02:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tE5wERhgxU/Render', 02:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:46 INFO - 'HOME': '/Users/cltbld', 02:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:46 INFO - 'LOGNAME': 'cltbld', 02:25:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:25:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:46 INFO - 'MOZ_NO_REMOTE': '1', 02:25:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:25:46 INFO - 'NO_EM_RESTART': '1', 02:25:46 INFO - 'PAGER': '/bin/cat', 02:25:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:46 INFO - 'PWD': '/builds/slave/test', 02:25:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:46 INFO - 'SHELL': '/bin/bash', 02:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLFlL20FzY/Listeners', 02:25:46 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:46 INFO - 'USER': 'cltbld', 02:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:46 INFO - 'XPC_FLAGS': '0x0', 02:25:46 INFO - 'XPC_SERVICE_NAME': '0', 02:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:46 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/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:25:46 INFO - mozversion application_buildid: 20161206004003 02:25:46 INFO - mozversion application_changeset: 46049b21980d67b13d6915c052a3025c273a1788 02:25:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:25:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:25:46 INFO - mozversion application_name: Firefox 02:25:46 INFO - mozversion application_remotingname: firefox-dev 02:25:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:25:46 INFO - mozversion application_vendor: Mozilla 02:25:46 INFO - mozversion application_version: 52.0a2 02:25:46 INFO - mozversion platform_buildid: 20161206004003 02:25:46 INFO - mozversion platform_changeset: 46049b21980d67b13d6915c052a3025c273a1788 02:25:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:25:46 INFO - mozversion platform_version: 52.0a2 02:25:46 INFO - using testdate: 1481019946 02:25:46 INFO - actual date: 1481019946 02:25:46 INFO - starting webserver on 'localhost:60139' 02:25:46 INFO - SUITE-START | Running 2 tests 02:25:46 INFO - TEST-START | dromaeo_css 02:25:46 INFO - operating with platform_type : mac_ 02:25:46 INFO - Initialising browser for dromaeo_css test... 02:25:46 INFO - TEST-INFO | started process 2538 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpRiGh97/profile http://localhost:60139/getInfo.html) 02:25:49 INFO - PROCESS | 2538 | 2016-12-06 02:25:49.427 plugin-container[2541:37048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x934b, name = 'com.apple.tsm.portname' 02:25:49 INFO - PROCESS | 2538 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:25:49 INFO - PROCESS | 2538 | 2016-12-06 02:25:49.427 plugin-container[2541:37048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 02:25:49 INFO - PROCESS | 2538 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:25:49 INFO - PROCESS | 2538 | 2016-12-06 02:25:49.427 plugin-container[2541:37048] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:25:49 INFO - PROCESS | 2538 | __metrics Screen width/height:1600/1200 02:25:49 INFO - PROCESS | 2538 | colorDepth:24 02:25:49 INFO - PROCESS | 2538 | Browser inner width/height: 1024/689 02:25:49 INFO - PROCESS | 2538 | __metrics 02:25:49 ERROR - PROCESS | 2538 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:25:50 INFO - TEST-INFO | 2538: exit 0 02:25:50 INFO - Browser initialized. 02:25:50 INFO - Running cycle 1/1 for dromaeo_css test... 02:25:50 INFO - TEST-INFO | started process 2542 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpRiGh97/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:53 INFO - PROCESS | 2542 | 2016-12-06 02:25:53.085 plugin-container[2543:37179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:25:53 INFO - PROCESS | 2542 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:25:53 INFO - PROCESS | 2542 | 2016-12-06 02:25:53.085 plugin-container[2543:37179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 02:25:53 INFO - PROCESS | 2542 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:25:53 INFO - PROCESS | 2542 | 2016-12-06 02:25:53.085 plugin-container[2543:37179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:02 INFO - PROCESS | 2542 | RSS: Main: 229883904 02:26:02 INFO - PROCESS | 2542 | 02:26:52 INFO - PROCESS | 2542 | 1481020012358 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:29:05 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60139/tests/dromaeo/cssquery-ext.html) 02:29:08 INFO - PROCESS | 2542 | RSS: Main: 233803776 02:29:08 INFO - PROCESS | 2542 | 02:32:10 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-ext.html (next: http://localhost:60139/tests/dromaeo/cssquery-jquery.html) 02:32:11 INFO - PROCESS | 2542 | RSS: Main: 243449856 02:32:11 INFO - PROCESS | 2542 | 02:35:13 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60139/tests/dromaeo/cssquery-mootools.html) 02:35:14 INFO - PROCESS | 2542 | RSS: Main: 246308864 02:35:14 INFO - PROCESS | 2542 | 02:38:16 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60139/tests/dromaeo/cssquery-prototype.html) 02:38:16 INFO - PROCESS | 2542 | RSS: Main: 246984704 02:38:16 INFO - PROCESS | 2542 | 02:41:19 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60139/tests/dromaeo/cssquery-yui.html) 02:41:20 INFO - PROCESS | 2542 | RSS: Main: 250556416 02:41:20 INFO - PROCESS | 2542 | 02:44:22 INFO - PROCESS | 2542 | Cycle 1(1): loaded http://localhost:60139/tests/dromaeo/cssquery-yui.html (next: http://localhost:60139/tests/dromaeo/cssquery-dojo.html) 02:44:23 INFO - PROCESS | 2542 | RSS: Main: 251273216 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | __start_tp_report 02:44:23 INFO - PROCESS | 2542 | _x_x_mozilla_page_load 02:44:23 INFO - PROCESS | 2542 | _x_x_mozilla_page_load_details 02:44:23 INFO - PROCESS | 2542 | |i|pagename|runs| 02:44:23 INFO - PROCESS | 2542 | |0;dojo.html;2708.2552298118026;3203.263249452626;3257.1857035741064;3295.9176020599484;3307.470804671253;5561.082421400469;5571.275734154559;5577.330720313563;5614.3543492498375;5636.830895073144;5551.306086739158;5605.1872478490595;5609.663420194791;5630.211770352154;5641.435856414357;5140.28036074948;5140.563052140573;5141.485851414847;5180.870478238036;5181.689098654093;4125.834966601333;5093.694378337312;5129.948700513003;5183.196604526304;5236.973815130919;5159.974200128994;5170.327857378536;5188.5435371382755;5198.064348417283;5222.817201397952;5025.949740502603;5068.138416469191;5159.71621560814;5173.017126745906;5212.635115541913;5019.247112933052;5085.974570127163;5091.949080509222;5106.668066575698;5115.079285728568;5535.335759708861;5636.323641163046;5736.311642602873;5750.971245143747;5758.539316854642;5934.643921364732;5993.550483713739;6024.09638554216;6026.8493287667725;6121.632702037892;6039.3960603939695;6041.848953776191;6050;6056.606320589148;6066.575339726221;5689.7724091036725;5698.088305871041;5704.77180912763;5746;5747.8563035924435;6093.482054025369;6163.19878415812;6201;6220.191375121296;6240.594361366497;5960.791372301948;5979.252593425822;5986.132010858489;6002.63984160943;6011.729472173763;6126.693665316806;6127.234095738033;6147.754089836446;6155;6163.445289923929;5887.175795388649;5892.558058145656;5937.910931336033;6030.517558595302;6037.154798328237;4377.584129507692;4643;5032.295478632995;6012.188354572164;6088.573799834057;4672.2057250267535;4755.738434386073;4765.976170119127;4787.952120478821;4829.9275510867365;4253.723507971972;4290.892727681833;4315.4605674290715;4348.021695118573;4351.651867850627;4849.709017458964;5524.696141712245;5661.264035675418;5730.426957304237;5744.971275143597;5443.747937974288;6064.696765161724;6173.32093469718;6195.96902015496;6207.441330280297;5607.52336051432;5609.719514024282;5636.464535869086;5649.745761440745;5654.886902261932;4456.353828694886;4745.908441058576;5060.2156665716875;5236.423993360727;5348.946510534923;5033.270175824412;5237.528622424;6215.378462153749;6234.0337247726675;6257.40554647326;6127.14220009208;6142.324344322032;6173.290071641819;6194.442500175006;6232.220972378453;6108.251008765869;6117.174181485442;6126.2123341605975;6175.62946223228;6194.690265486798;5018.071656743514;6080.635161890301;6091.604045737013;6103;6135.938640613536;6115.480184184395;6158.630482171084;6161.260648722003;6183.412575805291;6186.1648677427975;5798.1302804579655;5805.8548536286935;5861.179434879035;5930.406959304035;5983.222181116601;5869.119632055226;5910.261217347832;5915.231019967377;5921.052631578927;5929.584929055098;5649.830505084854;5701.543876489954;5797.594168408171;5800.883982320414;5813.651180929158;5739.225204597493;5838.386969368155;5901.232839730979;5904.173415721888;5967.194428752063;5788.450097240755;5802.912956305743;5840.503557197686;5852.268466441695;5866.178734977192 02:44:23 INFO - PROCESS | 2542 | |1;ext.html;465.7089319175515;486.9196582563877;511.42209303487056;513.0636588226483;536.6021513956144;29321.706782932164;31119;31295.68704312956;38697.03257418566;38744;40876.79561602188;41348.17303653925;41359.58640413595;41392.586074139246;41539.58460415395;9177.678781242674;9329;9378.671746488873;9381.484018378986;9385.296102792283;9345.76635584109;9450.385724927892;9458.716238512856;9461.432314061143;9463.57413916372;8016.398770092237;9381.202597779196;9385;9429.81140377192;9516.857247141299;9247.491387973692;9409.341346105775;9431.481268530228;9459.337846350758;9469.715908522687;9253.444793312356;9274.768130796716;9364.625414983393;9365.531723413802;9372.484513351763;38341.233175336616;38347.84956451311;38393;38596;38697.806510967544;3151.43274210642;3254.7721659483846;3311.966880331214;3378.0372593810894;3684.452071194829;22566.887165564232;29646.110616681533;29873.25316867074;30297.848510757307;30542.84728576343;3104.2549788050933;3140.309131990958;3177.380410819891;3211.2132527530903;3218.09893229896;473458;478507;478637;479900;479908;53321.2001819973;53704.194437083475;53715.462845371825;56727.43272567304;57890.710546447;60950;61105.69447152736;61453;61560.76878462406;61698.38301616926;15894.52316430509;16074.43739469113;16115.033098014157;16121.838781612269;16128.596785080232;10066.295359324777;11103.500342484607;11476.655700329004;12415.751684966432;12540.122191446693;13544.18734875891;15362.923185384225;16076.356945722275;16130.154344606846;16204.918975405284;3156.1162874395204;6501.1548498694865;6722.764703235461;6727.260001399842;6782.287859774653;841.9915800842035;927.1099744245465;972.7811242470387;976.3312131190157;997.2470784557613;21293.46766330823;28294.86820527197;29053;29563.965261216184;29598;1023.6212601337404;1044.472541366605;1083;1093.8796732359433;1145.1983611472003;14823.332950017275;15263.847361526243;15319.923400382926;15386.846131538765;15598.532044038697;477308;574877;581182;582689;582932;56446.717766410904;56469.71765141148;56807;57017.14474282889;57287;1338.110156745753;1363.3183408295852;1371.2595198592649;1373.0457332154256;1374.415873253863;27896.163115106578;28702.138935831957;28870.42259154847;29278.268043298234;29300.33745781777;53276.73361633167;54878.725606371714;54951.90096198134;58240.12639810491;58318.7084064577;594121;616204;623557;624788;625949;40860.1827963445;41416.171676566904;41486.79256603698;41794.955126120876;42016.78991605023;45248;45442.54557454383;45511.31733024074;45895.541044589125;45949.76288756794;37615.05962350854;38279.617203827605;38421.6157838418;39527.40708889312;39568.208635826544;36476;36507.81746091253;37292;37604.81197593995;38664.80667596644;36412;36635.45046824247;37211.813940931206;37558.62441375551;38681.80659096799;30267.394652106395;31669.841650791597;34266.31467370688;34420.31159376748;34494.82752586221;19551.511212219808;24995.000199991795;25210.873945630156;25256.873715631304;25619.487610247317 02:44:23 INFO - PROCESS | 2542 | |2;jquery.html;320.7434052757793;368.4767629965449;374.1562775940254;382.30420634445323;397.84822937595663;17978.91010544947;18508.259669613217;19069.14188861501;19496.610067798654;19716.901415492903;19257.22971081155;19404.223831046776;19423.611527769437;19574.608507829835;19722.50693732654;5542.944570554293;5795.739191736382;5796.768129274824;5797.913031304533;5801.100829371454;5668.858278543049;5968.1346204800275;6013.669248191348;6074.119252708355;6079.908801367983;5102.234664800272;5422;6009.4591486766185;6010;6028.3067447243475;6504.447121994636;6517.641529715864;6626.834329141761;6630.569013014138;6646.833829154261;5629.60592758507;5842.152887831241;5874.206982057453;5900.55745819061;5917.112433135021;12746.80879786804;12817.551385701548;12994;16121;24182.637260441108;2044.9795502044938;2103.779103194158;2146.4097373222335;2313.6876521669697;2951.719586639266;9695.078967498146;9785;10334.896651033469;12668.366581670973;14457.204853733041;1830.6979348407497;1840.68708319586;1858.3310008396966;1869.6634605770955;1893.091316168247;124474;125932;126360;127383.36308318492;130437;11331.546738130548;11464.656060318204;11709.770474100267;11749.706257343463;11842.11184161174;14350;14359.85640143606;14369.425222990963;14376.496822611158;14427.35076921541;6893.93106068943;8205.261526462647;8326.875096873551;8345.95827020861;8356.373272004625;8210.234799518132;8239.434507443566;8379.37154713399;8390.202930721694;8393.412461127784;4376.343548467756;4457.353683715908;4526.88682782933;4529.8641040768825;4566.13243483737;1847.086293407527;1914.4543805015783;1922.7019811929174;1931.1020375525447;1958.8922609256342;786.3159051625123;787.8896954426385;862.6930200745875;988.614440368257;1025.0620682075967;19169.80830191708;20874.06066727001;20929.372118836458;20964.266250681154;21093.472663183544;884.8672699095188;890.2521881619471;960.4621412009298;970.8737864077625;979.8775153105862;9562.330636855353;10306.536205870754;10894.67315980522;10920.726981825359;10940.781184376268;494572;500922;503444;592658;613906;124255;124565;126279.73720262863;138093;139044;934.4393363981693;984.1683777208391;1004.5128112865254;1005.2460654509118;1006.4565134827258;18552.44342669722;19041.333553325567;19166.425007249803;19256.518587035436;19287.51781205481;55372.72313638406;56280.718596406754;57577;57646.7117664409;58578.414215857294;78165.60917195378;79663.4067318662;81260.59369703113;83607.5819620898;84695;6051.3041000283765;7848.018997625297;7872.370210383041;7935.2064793520185;7942.523448593103;11284.717882052686;12182.695432614255;12231.877681223074;12397.194182378116;12462.1276510647;9906.752331191778;10015.248856335658;10620.20348473852;10747.516361763583;10866.13070954306;10551;10554.947225263824;10594.576216951236;10980.890191098306;11072.667819965414;8857.822843543221;10837.945810270898;10993.725156871142;11189.216754827085;11381.943090284496;7880.2513761192595;7947.403944704286;8227.958860205661;8243.546604936786;8324.084350721294;9610.327277090817;10755;10884.292520986111;10922.071623912056;11134.888651113386 02:44:23 INFO - PROCESS | 2542 | |3;mootools.html;1197.485081414991;1258.1444617659993;1298.2405292903652;1303.0032025500493;1497.6031351691804;7501.212372700869;7755.806104847382;8067.959660201698;8509.149085091496;8531.402801803877;6749.2913244109395;7976.561289129096;8248.381371397154;8355.623996920138;8358.247757701805;2084.208000959637;2304.9193278235266;2306.192832508617;2310.2722642367685;2313.8611683298973;2316.8494047886834;2355.5524450354465;2357.49313897512;2361.6929799126087;2367.9289621311386;2259.2318611672113;2273.7498875123724;2275.180934863448;2294.27730264966;2298.9310320690406;2246.472079061419;2265.0600000999566;2285.1773361589812;2288.645259984705;2297.035245197013;2319.52449747803;2334.113037045912;2355.7173139223232;2355.740868504463;2369.2536850891915;8301.543415112166;8335.333173346118;8342.582870856493;8350.206730360605;8354.206350396702;5035.471275516054;5062.645614806928;5199.974000130013;5213.791448342062;5242.135047717122;3399.3031428557088;3507.8070706111407;3624.5288112545463;3628.129248980252;3633.4368172933237;1055.6885718712997;1068.8343306787383;1073.6778966310076;1105.5522513382093;1107.1087774341652;9102.089791020844;10902.945485272603;11006.669799906096;11019.953104455064;11038.17213708967;4173.6661067114555;4414.3157810539415;4435.068635586498;4493.820247190141;4517.932231016537;8504.914950850412;8572.52851093196;8589.012263589642;8602.18279263469;8606.483610983361;5087.847364579068;5089.491050894881;5091.949080509222;5097.796088156507;5119.923201151985;4229.746215227096;4403.053343531156;4561.608709343682;4609.124266389375;4766.237402015661;2820.5205115130475;2925.254060214674;3425.092350527112;3744.6255374462335;3754.3992961126637;1129.813580759182;1153.9538418463335;1160.7330314027652;1162.7616338650557;1164.8002557365926;763.4007304266124;793.4089103617727;821.9342452603777;884.3897710579679;895.4582477600958;4844.32179494871;4897.8285759998225;5388.649737767033;5462.9726851365485;5706.743196556165;952.1620973543238;965;986.9605215791431;1002.8395456726888;1042.551702767802;3738.6822120120096;3832.770033797981;3834.9616503835164;3849.557300910431;3868.419737039467;8967.282617390592;9626.65226868239;9646.710598682052;10178.745531361626;10188.286819922683;10101.646442374482;10135.695929122137;10142.847857282146;10156.984301569784;10166.186705063577;7416.555006699616;7737.651805668758;7830.33442157423;7861.882071768928;7889.526628402199;5623.662580245198;5661.85845353853;5833.591648584728;5883.382244864228;5889.911651325147;9218.815623687355;9266.351355405326;9308.208802251884;9408.012158723235;9550.570224339783;7083.610401427971;7716.344110750649;9412.717618471457;9584.616615335588;9628.133467987915;6606.6036037837885;6834.316568343126;7144.928550714426;7280.672369743268;7286.307800758919;7708.49894756839;7925.207479252259;7943.364530837403;8058.194180581895;8059.43583949118;6873.828154296182;7062.576245425291;7227.060482137288;7231.710731570678;7318.597477138808;6997;7061.964690176722;7091.219965803654;7102.538335008208;7107.395871350786;6437.452816510649;6635.502337324623;6713.899291510729;6745.763898263537;6773.661316934232;4799.784009719641;4810.831620893252;4834.951650483591;4847.466778654275;4878.390201224847;4745.1221524018165;4775.976120119377;4796.424429068395;4911.410630724193;4974.00519896015 02:44:23 INFO - PROCESS | 2542 | |4;prototype.html;280.53431304035297;289.61336615618137;305.72332039504226;305.80734137493386;309.0157847256488;5357.464253574641;5484.561235101192;5485.259489968853;5580.302462192226;5582.692951887645;5090.78550830389;5316.441773613773;5453.890922181554;5495.780168793243;5528.917066243999;3310.940499040314;3853.2100919311474;4193.832246710143;4216.219999300124;4220.36694495825;3453.4474484082552;3677.8896633100917;3818.599047100056;4031.516218053823;4229.450171477703;3594.748367614268;3764.717646176534;4103.446034785311;4109.342505199169;4121.237571049365;3455.3953058214965;3669.8899033028783;3710.1466662667603;3775.5275442577417;3911.86555898789;3372.5615669962995;3574.267275208602;3583.7312201584987;3588.3361578107874;3590.874319398835;5137.74311284438;5357.4106848246665;5379.704116273642;5385.569154467633;5407.215953686733;3811.4663947047156;4319.50325712544;4569.314602809572;4692.4603670577635;4715.339852420664;4985.027919555688;5461.2627295315415;5634.605577609569;5771.913421298684;5830.32951210612;3456.3087382523595;3780.9621903781162;3901.6878649707955;3926.2540117377894;3943.507061617298;5290.285811415448;6006.279246490449;6025.849353766147;6035.245594300713;6044.491536012593;3939.9212015759526;3974.940375894364;3985.0074962518743;3996.7202295839593;3997.4803275574573;3850.845966161322;3986.242613903349;4040.4949381327333;4050.5746896576024;4060.4315395844606;4101.343784994387;4249.532551419341;4279.700420970565;4445.955540444619;4686.156491831503;4899.951000489949;5093.567046800989;5386.326709161355;5822.679752613562;5868.383819698955;1240.5906051003146;1265.993670031644;1280.564608033273;1288.865798097668;1319.399673148715;851.7827953871723;866.7703058689452;886.902440731519;888.7689200807707;890.3100097424418;4874.488178741181;5269.657472264291;5307.734613269321;5315.813946511854;5319.946800532022;4952.851414457572;5231.450697676765;5725.599208055396;5754.223179870663;5779.393163717833;4765.618750499952;4854.805807767721;5003.399592048978;5258.8948221036135;5279.868003299872;2587.327294903301;2666.706662267149;2699.757021868041;2848.9031722786876;2964.614600101973;5507.284053073155;5839.854003649858;5870.385643947863;5887.643144073355;5892;5073.974630126826;5362.383325917492;5635.266376243975;5696.857578560486;5815.592908496449;5354.330708661418;5413.9427017733005;5842.211301474332;5849.912251316234;5876.6777475067765;5173.586113110867;5249.921251181159;5691.069099413722;5727.8568035799435;5756.158029430542;5193;5438.918416223839;5602.1512900741845;5603.943960560342;5632.690202038927;5014.87462813418;5592.832215033555;5863.0032894408905;5886.499647530008;5886.823395298148;3265.9510107348883;3550;3647.908802279964;3757.0982964087875;3774.1885494618787;5044.798208071636;5381.650192737459;5432.483914028161;5583.888322233452;5645.181448689835;4732.834350797705;5735.719387270931;5897.085951677497;5897.3807750185615;5926.555508336805;5160.845174644766;5849.824505264849;5879.853003674943;5899.174115623902;5911.674857882857;5123.282740416419;5554.361248456479;5688.886222275614;5748.252727145612;5766.596338256281;4465.843695470643;5614.88373952444;5809.5352371811;5868.354481007171;5869.236999190249;5051.646384753032;5141.015712822593;5496;5619.859503512282;5756.597038207286 02:44:23 INFO - PROCESS | 2542 | |5;yui.html;603.7343568829715;692.2661978302999;712.1489819665495;756.5498528375618;794.2930791595481;13835.58493245203;14426.855731442682;14773.704525909476;14879.776803347944;14997.62505937352;13285.867141328585;13346.399412026458;13575.796363054538;13601.319934003284;13630.318484075806;800.2077942836579;813.0609146435876;813.8372325534887;814.0800894988655;814.5723495165026;648.9221293444793;670.4468813229081;764.9770506884803;780.3601047141346;792.825578372757;867.5402036920442;867.8437881181385;867.8828358171661;870.6299822575412;872.7599910024762;854.5556310718423;862.6808081010035;863.4603372891943;863.96976105836;865.2947847504296;611.2237458427816;617.7559863853797;629.889769290377;632.1339764522634;633.2053273142224;13745.931270343684;13764.518241861486;13849.446022159102;13865.306734663327;13890.861091389159;12297.815532767016;12485.188462749893;12509.874901250962;12577.245365278113;12644.431000604904;10893.564257429693;11301.49143288554;11305.604303849408;11342.319460832294;11355.659330220107;3530.75284730069;3560.2523470071314;3560.3413368526644;3584.713222942158;3697.907552311187;70755;89532.99999999935;93844;95026.04973950241;96098;9938.304318697621;10051.798964020678;10376.533056012497;10473.84289235662;10535.683929482127;19878;19880.90059549722;19922.90038549827;19923.501912452306;19926.50183745389;7288.344049035506;7423.146338171072;7506.962465187748;7620.390368770485;7657.234276572298;4154.065335299594;4194.74157752673;4213.304804707189;4251.638610718123;4273.529911709709;3272.149241197306;3371.477420999749;3391.101358140095;3606.441001644749;3754.7747135171976;615.7807541068709;660.3693472733528;662.1359126340172;664.6676661669165;683.0505597219868;487.529533999692;569.6012791046285;622.4273668225244;636.2905360523076;654.326044174501;7901.367890568741;8618.827623447622;8645.827083458422;8870.778230544289;9136.26909847224;501.59872102318;503.67764630636424;505.1765622036058;576.8990426675399;586.7037146241121;4241.491021077491;4244.808983595745;4445.466544014675;4514.954850451453;4541.909161816745;105196;106440.46779766052;108697;110181;110960;59247.40752592505;63636.68181659062;88258.67611985825;88832;93711;62786.68606657029;82144;83902;84326;86503.56748216345;8539.74380768578;8582.227599516074;8666.913330866862;8719.084496127814;8752.912470875464;53858.730706346214;54025.45974540361;54554.72722636361;55062.724686376314;56118.158227625805;76883.61558192174;77461.61269193844;78679.21320786948;78911;79411;10964.616238431936;11184;11202.935721106482;11297;11478.655640330804;11983.700407489883;12284.1401101922;13128.212307261212;13698.794518082037;13926.93036534811;13066.346682666019;13372.665683357995;13377.933110334387;13711.314434278047;13801.930990344985;12698.619041428772;13431.992600554802;13497.392617332047;13523.526676566273;13560.186388816308;11969.222000569935;12872.613821585368;13158;13378.331083445593;13552.32238388057;10217.642382516577;10739.516721747385;11024.118070554498;11183.776324473627;11313.943430282796;10178.898211017795;10217.693469195936;10218.335808172445;11048.558057677603;11105.888941110485 02:44:23 INFO - PROCESS | 2542 | __end_tp_report 02:44:23 INFO - PROCESS | 2542 | __start_cc_report 02:44:23 INFO - PROCESS | 2542 | _x_x_mozilla_cycle_collect,373 02:44:23 INFO - PROCESS | 2542 | __end_cc_report 02:44:23 INFO - PROCESS | 2542 | __startTimestamp1481021062997__endTimestamp 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | ------- Summary: start ------- 02:44:23 INFO - PROCESS | 2542 | Number of tests: 6 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#0] dojo.html Cycles:165 Average:5558.58 Median:5747.86 stddev:665.56 (11.6%) stddev-sans-first:628.93 02:44:23 INFO - PROCESS | 2542 | Values: 2708.3 3203.3 3257.2 3295.9 3307.5 5561.1 5571.3 5577.3 5614.4 5636.8 5551.3 5605.2 5609.7 5630.2 5641.4 5140.3 5140.6 5141.5 5180.9 5181.7 4125.8 5093.7 5129.9 5183.2 5237.0 5160.0 5170.3 5188.5 5198.1 5222.8 5025.9 5068.1 5159.7 5173.0 5212.6 5019.2 5086.0 5091.9 5106.7 5115.1 5535.3 5636.3 5736.3 5751.0 5758.5 5934.6 5993.6 6024.1 6026.8 6121.6 6039.4 6041.8 6050.0 6056.6 6066.6 5689.8 5698.1 5704.8 5746.0 5747.9 6093.5 6163.2 6201.0 6220.2 6240.6 5960.8 5979.3 5986.1 6002.6 6011.7 6126.7 6127.2 6147.8 6155.0 6163.4 5887.2 5892.6 5937.9 6030.5 6037.2 4377.6 4643.0 5032.3 6012.2 6088.6 4672.2 4755.7 4766.0 4788.0 4829.9 4253.7 4290.9 4315.5 4348.0 4351.7 4849.7 5524.7 5661.3 5730.4 5745.0 5443.7 6064.7 6173.3 6196.0 6207.4 5607.5 5609.7 5636.5 5649.7 5654.9 4456.4 4745.9 5060.2 5236.4 5348.9 5033.3 5237.5 6215.4 6234.0 6257.4 6127.1 6142.3 6173.3 6194.4 6232.2 6108.3 6117.2 6126.2 6175.6 6194.7 5018.1 6080.6 6091.6 6103.0 6135.9 6115.5 6158.6 6161.3 6183.4 6186.2 5798.1 5805.9 5861.2 5930.4 5983.2 5869.1 5910.3 5915.2 5921.1 5929.6 5649.8 5701.5 5797.6 5800.9 5813.7 5739.2 5838.4 5901.2 5904.2 5967.2 5788.5 5802.9 5840.5 5852.3 5866.2 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#1] ext.html Cycles:180 Average:68374.08 Median:40214.20 stddev:148281.74 (368.7%) stddev-sans-first:148610.05 02:44:23 INFO - PROCESS | 2542 | Values: 465.7 486.9 511.4 513.1 536.6 29321.7 31119.0 31295.7 38697.0 38744.0 40876.8 41348.2 41359.6 41392.6 41539.6 9177.7 9329.0 9378.7 9381.5 9385.3 9345.8 9450.4 9458.7 9461.4 9463.6 8016.4 9381.2 9385.0 9429.8 9516.9 9247.5 9409.3 9431.5 9459.3 9469.7 9253.4 9274.8 9364.6 9365.5 9372.5 38341.2 38347.8 38393.0 38596.0 38697.8 3151.4 3254.8 3312.0 3378.0 3684.5 22566.9 29646.1 29873.3 30297.8 30542.8 3104.3 3140.3 3177.4 3211.2 3218.1 473458.0 478507.0 478637.0 479900.0 479908.0 53321.2 53704.2 53715.5 56727.4 57890.7 60950.0 61105.7 61453.0 61560.8 61698.4 15894.5 16074.4 16115.0 16121.8 16128.6 10066.3 11103.5 11476.7 12415.8 12540.1 13544.2 15362.9 16076.4 16130.2 16204.9 3156.1 6501.2 6722.8 6727.3 6782.3 842.0 927.1 972.8 976.3 997.2 21293.5 28294.9 29053.0 29564.0 29598.0 1023.6 1044.5 1083.0 1093.9 1145.2 14823.3 15263.8 15319.9 15386.8 15598.5 477308.0 574877.0 581182.0 582689.0 582932.0 56446.7 56469.7 56807.0 57017.1 57287.0 1338.1 1363.3 1371.3 1373.0 1374.4 27896.2 28702.1 28870.4 29278.3 29300.3 53276.7 54878.7 54951.9 58240.1 58318.7 594121.0 616204.0 623557.0 624788.0 625949.0 40860.2 41416.2 41486.8 41795.0 42016.8 45248.0 45442.5 45511.3 45895.5 45949.8 37615.1 38279.6 38421.6 39527.4 39568.2 36476.0 36507.8 37292.0 37604.8 38664.8 36412.0 36635.5 37211.8 37558.6 38681.8 30267.4 31669.8 34266.3 34420.3 34494.8 19551.5 24995.0 25210.9 25256.9 25619.5 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#2] jquery.html Cycles:180 Average:33583.99 Median:13248.16 stddev:91747.20 (692.5%) stddev-sans-first:91970.58 02:44:23 INFO - PROCESS | 2542 | Values: 320.7 368.5 374.2 382.3 397.8 17978.9 18508.3 19069.1 19496.6 19716.9 19257.2 19404.2 19423.6 19574.6 19722.5 5542.9 5795.7 5796.8 5797.9 5801.1 5668.9 5968.1 6013.7 6074.1 6079.9 5102.2 5422.0 6009.5 6010.0 6028.3 6504.4 6517.6 6626.8 6630.6 6646.8 5629.6 5842.2 5874.2 5900.6 5917.1 12746.8 12817.6 12994.0 16121.0 24182.6 2045.0 2103.8 2146.4 2313.7 2951.7 9695.1 9785.0 10334.9 12668.4 14457.2 1830.7 1840.7 1858.3 1869.7 1893.1 124474.0 125932.0 126360.0 127383.4 130437.0 11331.5 11464.7 11709.8 11749.7 11842.1 14350.0 14359.9 14369.4 14376.5 14427.4 6893.9 8205.3 8326.9 8346.0 8356.4 8210.2 8239.4 8379.4 8390.2 8393.4 4376.3 4457.4 4526.9 4529.9 4566.1 1847.1 1914.5 1922.7 1931.1 1958.9 786.3 787.9 862.7 988.6 1025.1 19169.8 20874.1 20929.4 20964.3 21093.5 884.9 890.3 960.5 970.9 979.9 9562.3 10306.5 10894.7 10920.7 10940.8 494572.0 500922.0 503444.0 592658.0 613906.0 124255.0 124565.0 126279.7 138093.0 139044.0 934.4 984.2 1004.5 1005.2 1006.5 18552.4 19041.3 19166.4 19256.5 19287.5 55372.7 56280.7 57577.0 57646.7 58578.4 78165.6 79663.4 81260.6 83607.6 84695.0 6051.3 7848.0 7872.4 7935.2 7942.5 11284.7 12182.7 12231.9 12397.2 12462.1 9906.8 10015.2 10620.2 10747.5 10866.1 10551.0 10554.9 10594.6 10980.9 11072.7 8857.8 10837.9 10993.7 11189.2 11381.9 7880.3 7947.4 8228.0 8243.5 8324.1 9610.3 10755.0 10884.3 10922.1 11134.9 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#3] mootools.html Cycles:180 Average:5347.65 Median:5049.06 stddev:2925.92 (57.9%) stddev-sans-first:2917.49 02:44:23 INFO - PROCESS | 2542 | Values: 1197.5 1258.1 1298.2 1303.0 1497.6 7501.2 7755.8 8068.0 8509.1 8531.4 6749.3 7976.6 8248.4 8355.6 8358.2 2084.2 2304.9 2306.2 2310.3 2313.9 2316.8 2355.6 2357.5 2361.7 2367.9 2259.2 2273.7 2275.2 2294.3 2298.9 2246.5 2265.1 2285.2 2288.6 2297.0 2319.5 2334.1 2355.7 2355.7 2369.3 8301.5 8335.3 8342.6 8350.2 8354.2 5035.5 5062.6 5200.0 5213.8 5242.1 3399.3 3507.8 3624.5 3628.1 3633.4 1055.7 1068.8 1073.7 1105.6 1107.1 9102.1 10902.9 11006.7 11020.0 11038.2 4173.7 4414.3 4435.1 4493.8 4517.9 8504.9 8572.5 8589.0 8602.2 8606.5 5087.8 5089.5 5091.9 5097.8 5119.9 4229.7 4403.1 4561.6 4609.1 4766.2 2820.5 2925.3 3425.1 3744.6 3754.4 1129.8 1154.0 1160.7 1162.8 1164.8 763.4 793.4 821.9 884.4 895.5 4844.3 4897.8 5388.6 5463.0 5706.7 952.2 965.0 987.0 1002.8 1042.6 3738.7 3832.8 3835.0 3849.6 3868.4 8967.3 9626.7 9646.7 10178.7 10188.3 10101.6 10135.7 10142.8 10157.0 10166.2 7416.6 7737.7 7830.3 7861.9 7889.5 5623.7 5661.9 5833.6 5883.4 5889.9 9218.8 9266.4 9308.2 9408.0 9550.6 7083.6 7716.3 9412.7 9584.6 9628.1 6606.6 6834.3 7144.9 7280.7 7286.3 7708.5 7925.2 7943.4 8058.2 8059.4 6873.8 7062.6 7227.1 7231.7 7318.6 6997.0 7062.0 7091.2 7102.5 7107.4 6437.5 6635.5 6713.9 6745.8 6773.7 4799.8 4810.8 4835.0 4847.5 4878.4 4745.1 4776.0 4796.4 4911.4 4974.0 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#4] prototype.html Cycles:180 Average:4557.81 Median:5212.23 stddev:1427.18 (27.4%) stddev-sans-first:1394.61 02:44:23 INFO - PROCESS | 2542 | Values: 280.5 289.6 305.7 305.8 309.0 5357.5 5484.6 5485.3 5580.3 5582.7 5090.8 5316.4 5453.9 5495.8 5528.9 3310.9 3853.2 4193.8 4216.2 4220.4 3453.4 3677.9 3818.6 4031.5 4229.5 3594.7 3764.7 4103.4 4109.3 4121.2 3455.4 3669.9 3710.1 3775.5 3911.9 3372.6 3574.3 3583.7 3588.3 3590.9 5137.7 5357.4 5379.7 5385.6 5407.2 3811.5 4319.5 4569.3 4692.5 4715.3 4985.0 5461.3 5634.6 5771.9 5830.3 3456.3 3781.0 3901.7 3926.3 3943.5 5290.3 6006.3 6025.8 6035.2 6044.5 3939.9 3974.9 3985.0 3996.7 3997.5 3850.8 3986.2 4040.5 4050.6 4060.4 4101.3 4249.5 4279.7 4446.0 4686.2 4900.0 5093.6 5386.3 5822.7 5868.4 1240.6 1266.0 1280.6 1288.9 1319.4 851.8 866.8 886.9 888.8 890.3 4874.5 5269.7 5307.7 5315.8 5319.9 4952.9 5231.5 5725.6 5754.2 5779.4 4765.6 4854.8 5003.4 5258.9 5279.9 2587.3 2666.7 2699.8 2848.9 2964.6 5507.3 5839.9 5870.4 5887.6 5892.0 5074.0 5362.4 5635.3 5696.9 5815.6 5354.3 5413.9 5842.2 5849.9 5876.7 5173.6 5249.9 5691.1 5727.9 5756.2 5193.0 5438.9 5602.2 5603.9 5632.7 5014.9 5592.8 5863.0 5886.5 5886.8 3266.0 3550.0 3647.9 3757.1 3774.2 5044.8 5381.7 5432.5 5583.9 5645.2 4732.8 5735.7 5897.1 5897.4 5926.6 5160.8 5849.8 5879.9 5899.2 5911.7 5123.3 5554.4 5688.9 5748.3 5766.6 4465.8 5614.9 5809.5 5868.4 5869.2 5051.6 5141.0 5496.0 5619.9 5756.6 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - PROCESS | 2542 | [#5] yui.html Cycles:180 Average:19921.25 Median:4359.50 stddev:29022.11 (665.7%) stddev-sans-first:29067.28 02:44:23 INFO - PROCESS | 2542 | Values: 603.7 692.3 712.1 756.5 794.3 13835.6 14426.9 14773.7 14879.8 14997.6 13285.9 13346.4 13575.8 13601.3 13630.3 800.2 813.1 813.8 814.1 814.6 648.9 670.4 765.0 780.4 792.8 867.5 867.8 867.9 870.6 872.8 854.6 862.7 863.5 864.0 865.3 611.2 617.8 629.9 632.1 633.2 13745.9 13764.5 13849.4 13865.3 13890.9 12297.8 12485.2 12509.9 12577.2 12644.4 10893.6 11301.5 11305.6 11342.3 11355.7 3530.8 3560.3 3560.3 3584.7 3697.9 70755.0 89533.0 93844.0 95026.0 96098.0 9938.3 10051.8 10376.5 10473.8 10535.7 19878.0 19880.9 19922.9 19923.5 19926.5 7288.3 7423.1 7507.0 7620.4 7657.2 4154.1 4194.7 4213.3 4251.6 4273.5 3272.1 3371.5 3391.1 3606.4 3754.8 615.8 660.4 662.1 664.7 683.1 487.5 569.6 622.4 636.3 654.3 7901.4 8618.8 8645.8 8870.8 9136.3 501.6 503.7 505.2 576.9 586.7 4241.5 4244.8 4445.5 4515.0 4541.9 105196.0 106440.5 108697.0 110181.0 110960.0 59247.4 63636.7 88258.7 88832.0 93711.0 62786.7 82144.0 83902.0 84326.0 86503.6 8539.7 8582.2 8666.9 8719.1 8752.9 53858.7 54025.5 54554.7 55062.7 56118.2 76883.6 77461.6 78679.2 78911.0 79411.0 10964.6 11184.0 11202.9 11297.0 11478.7 11983.7 12284.1 13128.2 13698.8 13926.9 13066.3 13372.7 13377.9 13711.3 13801.9 12698.6 13432.0 13497.4 13523.5 13560.2 11969.2 12872.6 13158.0 13378.3 13552.3 10217.6 10739.5 11024.1 11183.8 11313.9 10178.9 10217.7 10218.3 11048.6 11105.9 02:44:23 INFO - PROCESS | 2542 | -------- Summary: end -------- 02:44:23 INFO - PROCESS | 2542 | 02:44:23 INFO - TEST-INFO | 2542: exit 0 02:44:23 INFO - TEST-OK | dromaeo_css | took 1117238ms 02:44:23 INFO - TEST-START | kraken 02:44:23 INFO - operating with platform_type : mac_ 02:44:23 INFO - Initialising browser for kraken test... 02:44:23 INFO - TEST-INFO | started process 2547 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmphwz7_v/profile http://localhost:60139/getInfo.html) 02:44:26 INFO - PROCESS | 2547 | 2016-12-06 02:44:26.621 plugin-container[2549:39451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 02:44:26 INFO - PROCESS | 2547 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:44:26 INFO - PROCESS | 2547 | 2016-12-06 02:44:26.621 plugin-container[2549:39451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a13, name = 'com.apple.CFPasteboardClient' 02:44:26 INFO - PROCESS | 2547 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:44:26 INFO - PROCESS | 2547 | 2016-12-06 02:44:26.622 plugin-container[2549:39451] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:44:26 INFO - PROCESS | 2547 | __metrics Screen width/height:1600/1200 02:44:26 INFO - PROCESS | 2547 | colorDepth:24 02:44:26 INFO - PROCESS | 2547 | Browser inner width/height: 1024/689 02:44:26 INFO - PROCESS | 2547 | __metrics 02:44:26 ERROR - PROCESS | 2547 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:44:26 INFO - PROCESS | 2547 | 02:44:26 INFO - PROCESS | 2547 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xEC0015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 02:44:26 INFO - PROCESS | 2547 | 02:44:27 INFO - TEST-INFO | 2547: exit 0 02:44:27 INFO - Browser initialized. 02:44:27 INFO - Running cycle 1/1 for kraken test... 02:44:27 INFO - TEST-INFO | started process 2550 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmphwz7_v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:44:30 INFO - PROCESS | 2550 | 2016-12-06 02:44:30.161 plugin-container[2551:39584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 02:44:30 INFO - PROCESS | 2550 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:44:30 INFO - PROCESS | 2550 | 2016-12-06 02:44:30.161 plugin-container[2551:39584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 02:44:30 INFO - PROCESS | 2550 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:44:30 INFO - PROCESS | 2550 | 2016-12-06 02:44:30.161 plugin-container[2551:39584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:44:39 INFO - PROCESS | 2550 | RSS: Main: 229560320 02:44:39 INFO - PROCESS | 2550 | 02:44:42 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60139/tests/kraken/driver.html?testName=audio-beat-detection) 02:44:43 INFO - PROCESS | 2550 | RSS: Main: 241369088 02:44:43 INFO - PROCESS | 2550 | 02:44:46 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60139/tests/kraken/driver.html?testName=audio-dft) 02:44:46 INFO - PROCESS | 2550 | RSS: Main: 248999936 02:44:46 INFO - PROCESS | 2550 | 02:44:50 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60139/tests/kraken/driver.html?testName=audio-fft) 02:44:50 INFO - PROCESS | 2550 | RSS: Main: 252850176 02:44:50 INFO - PROCESS | 2550 | 02:44:53 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60139/tests/kraken/driver.html?testName=audio-oscillator) 02:44:53 INFO - PROCESS | 2550 | RSS: Main: 253276160 02:44:53 INFO - PROCESS | 2550 | 02:44:55 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60139/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:44:56 INFO - PROCESS | 2550 | RSS: Main: 253210624 02:44:56 INFO - PROCESS | 2550 | 02:44:59 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60139/tests/kraken/driver.html?testName=imaging-darkroom) 02:45:00 INFO - PROCESS | 2550 | RSS: Main: 258985984 02:45:00 INFO - PROCESS | 2550 | 02:45:02 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60139/tests/kraken/driver.html?testName=imaging-desaturate) 02:45:03 INFO - PROCESS | 2550 | RSS: Main: 257802240 02:45:03 INFO - PROCESS | 2550 | 02:45:06 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60139/tests/kraken/driver.html?testName=json-parse-financial) 02:45:07 INFO - PROCESS | 2550 | RSS: Main: 258023424 02:45:07 INFO - PROCESS | 2550 | 02:45:09 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60139/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:45:09 INFO - PROCESS | 2550 | RSS: Main: 256991232 02:45:09 INFO - PROCESS | 2550 | 02:45:11 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:45:12 INFO - PROCESS | 2550 | RSS: Main: 257011712 02:45:12 INFO - PROCESS | 2550 | 02:45:14 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:45:15 INFO - PROCESS | 2550 | RSS: Main: 257089536 02:45:15 INFO - PROCESS | 2550 | 02:45:18 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:45:19 INFO - PROCESS | 2550 | RSS: Main: 257355776 02:45:19 INFO - PROCESS | 2550 | 02:45:22 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:45:22 INFO - PROCESS | 2550 | RSS: Main: 257208320 02:45:22 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | Cycle 1(1): loaded http://localhost:60139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60139/tests/kraken/driver.html?testName=ai-astar) 02:45:24 INFO - PROCESS | 2550 | RSS: Main: 259039232 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | __start_tp_report 02:45:24 INFO - PROCESS | 2550 | _x_x_mozilla_page_load 02:45:24 INFO - PROCESS | 2550 | _x_x_mozilla_page_load_details 02:45:24 INFO - PROCESS | 2550 | |i|pagename|runs| 02:45:24 INFO - PROCESS | 2550 | |0;ai-astar;113;119;117;139;180;117;118;120;131;177 02:45:24 INFO - PROCESS | 2550 | |1;audio-beat-detection;164;130;105;149;122;144;104;131;178;137 02:45:24 INFO - PROCESS | 2550 | |2;audio-dft;202;199;198;199;197;198;277;238;195;201 02:45:24 INFO - PROCESS | 2550 | |3;audio-fft;84;65;83;111;88;59;61;70;79;55 02:45:24 INFO - PROCESS | 2550 | |4;audio-oscillator;85;71;92;71;69;71;105;69;59;116 02:45:24 INFO - PROCESS | 2550 | |5;imaging-gaussian-blur;123;102;87;131;130;88;94;90;106;113 02:45:24 INFO - PROCESS | 2550 | |6;imaging-darkroom;96;97;96;127;154;113;96;96;97;96 02:45:24 INFO - PROCESS | 2550 | |7;imaging-desaturate;88;92;93;139;74;139;129;140;131;138 02:45:24 INFO - PROCESS | 2550 | |8;json-parse-financial;57;58;56;59;62;53;56;68;51;56 02:45:24 INFO - PROCESS | 2550 | |9;json-stringify-tinderbox;86;111;114;126;79;71;86;106;108;73 02:45:24 INFO - PROCESS | 2550 | |10;stanford-crypto-aes;85;81;103;105;105;85;77;100;105;83 02:45:24 INFO - PROCESS | 2550 | |11;stanford-crypto-ccm;161;149;162;163;159;162;168;163;198;153 02:45:24 INFO - PROCESS | 2550 | |12;stanford-crypto-pbkdf2;171;175;168;173;178;235;171;218;160;229 02:45:24 INFO - PROCESS | 2550 | |13;stanford-crypto-sha256-iterative;68;71;70;71;81;87;82;70;68;69 02:45:24 INFO - PROCESS | 2550 | __end_tp_report 02:45:24 INFO - PROCESS | 2550 | __start_cc_report 02:45:24 INFO - PROCESS | 2550 | _x_x_mozilla_cycle_collect,985 02:45:24 INFO - PROCESS | 2550 | __end_cc_report 02:45:24 INFO - PROCESS | 2550 | __startTimestamp1481021124955__endTimestamp 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | ------- Summary: start ------- 02:45:24 INFO - PROCESS | 2550 | Number of tests: 14 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#0] ai-astar Cycles:10 Average:133.10 Median:125.50 stddev:25.14 (20.0%) stddev-sans-first:25.59 02:45:24 INFO - PROCESS | 2550 | Values: 113.0 119.0 117.0 139.0 180.0 117.0 118.0 120.0 131.0 177.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:140.50 stddev:23.62 (16.8%) stddev-sans-first:22.85 02:45:24 INFO - PROCESS | 2550 | Values: 164.0 130.0 105.0 149.0 122.0 144.0 104.0 131.0 178.0 137.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#2] audio-dft Cycles:10 Average:210.40 Median:200.00 stddev:26.54 (13.3%) stddev-sans-first:27.98 02:45:24 INFO - PROCESS | 2550 | Values: 202.0 199.0 198.0 199.0 197.0 198.0 277.0 238.0 195.0 201.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#3] audio-fft Cycles:10 Average:75.50 Median:74.50 stddev:17.00 (22.8%) stddev-sans-first:17.75 02:45:24 INFO - PROCESS | 2550 | Values: 84.0 65.0 83.0 111.0 88.0 59.0 61.0 70.0 79.0 55.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:71.00 stddev:18.29 (25.8%) stddev-sans-first:19.33 02:45:24 INFO - PROCESS | 2550 | Values: 85.0 71.0 92.0 71.0 69.0 71.0 105.0 69.0 59.0 116.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#5] imaging-gaussian-blur Cycles:10 Average:106.40 Median:109.00 stddev:17.12 (15.7%) stddev-sans-first:17.07 02:45:24 INFO - PROCESS | 2550 | Values: 123.0 102.0 87.0 131.0 130.0 88.0 94.0 90.0 106.0 113.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:96.00 stddev:19.58 (20.4%) stddev-sans-first:20.37 02:45:24 INFO - PROCESS | 2550 | Values: 96.0 97.0 96.0 127.0 154.0 113.0 96.0 96.0 97.0 96.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#7] imaging-desaturate Cycles:10 Average:116.30 Median:107.00 stddev:26.17 (24.5%) stddev-sans-first:25.68 02:45:24 INFO - PROCESS | 2550 | Values: 88.0 92.0 93.0 139.0 74.0 139.0 129.0 140.0 131.0 138.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:4.74 (8.2%) stddev-sans-first:5.02 02:45:24 INFO - PROCESS | 2550 | Values: 57.0 58.0 56.0 59.0 62.0 53.0 56.0 68.0 51.0 56.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#9] json-stringify-tinderbox Cycles:10 Average:96.00 Median:72.00 stddev:19.25 (26.7%) stddev-sans-first:20.08 02:45:24 INFO - PROCESS | 2550 | Values: 86.0 111.0 114.0 126.0 79.0 71.0 86.0 106.0 108.0 73.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:79.00 stddev:11.59 (14.7%) stddev-sans-first:11.94 02:45:24 INFO - PROCESS | 2550 | Values: 85.0 81.0 103.0 105.0 105.0 85.0 77.0 100.0 105.0 83.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:162.50 stddev:13.17 (8.1%) stddev-sans-first:13.93 02:45:24 INFO - PROCESS | 2550 | Values: 161.0 149.0 162.0 163.0 159.0 162.0 168.0 163.0 198.0 153.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:176.50 stddev:27.98 (15.9%) stddev-sans-first:29.01 02:45:24 INFO - PROCESS | 2550 | Values: 171.0 175.0 168.0 173.0 178.0 235.0 171.0 218.0 160.0 229.0 02:45:24 INFO - PROCESS | 2550 | 02:45:24 INFO - PROCESS | 2550 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:71.00 stddev:6.90 (9.7%) stddev-sans-first:7.00 02:45:24 INFO - PROCESS | 2550 | Values: 68.0 71.0 70.0 71.0 81.0 87.0 82.0 70.0 68.0 69.0 02:45:24 INFO - PROCESS | 2550 | -------- Summary: end -------- 02:45:24 INFO - PROCESS | 2550 | 02:45:26 INFO - TEST-INFO | 2550: exit 0 02:45:26 INFO - TEST-OK | kraken | took 62405ms 02:45:26 INFO - SUITE-END | took 1179s 02:45:26 INFO - Completed test suite (00:19:39) 02:45:26 INFO - javascript benchmark 02:45:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7086.512975345068, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2708.2552298118026, 3203.263249452626, 3257.1857035741064, 3295.9176020599484, 3307.470804671253, 5561.082421400469, 5571.275734154559, 5577.330720313563, 5614.3543492498375, 5636.830895073144, 5551.306086739158, 5605.1872478490595, 5609.663420194791, 5630.211770352154, 5641.435856414357, 5140.28036074948, 5140.563052140573, 5141.485851414847, 5180.870478238036, 5181.689098654093, 4125.834966601333, 5093.694378337312, 5129.948700513003, 5183.196604526304, 5236.973815130919, 5159.974200128994, 5170.327857378536, 5188.5435371382755, 5198.064348417283, 5222.817201397952, 5025.949740502603, 5068.138416469191, 5159.71621560814, 5173.017126745906, 5212.635115541913, 5019.247112933052, 5085.974570127163, 5091.949080509222, 5106.668066575698, 5115.079285728568, 5535.335759708861, 5636.323641163046, 5736.311642602873, 5750.971245143747, 5758.539316854642, 5934.643921364732, 5993.550483713739, 6024.09638554216, 6026.8493287667725, 6121.632702037892, 6039.3960603939695, 6041.848953776191, 6050.0, 6056.606320589148, 6066.575339726221, 5689.7724091036725, 5698.088305871041, 5704.77180912763, 5746.0, 5747.8563035924435, 6093.482054025369, 6163.19878415812, 6201.0, 6220.191375121296, 6240.594361366497, 5960.791372301948, 5979.252593425822, 5986.132010858489, 6002.63984160943, 6011.729472173763, 6126.693665316806, 6127.234095738033, 6147.754089836446, 6155.0, 6163.445289923929, 5887.175795388649, 5892.558058145656, 5937.910931336033, 6030.517558595302, 6037.154798328237, 4377.584129507692, 4643.0, 5032.295478632995, 6012.188354572164, 6088.573799834057, 4672.2057250267535, 4755.738434386073, 4765.976170119127, 4787.952120478821, 4829.9275510867365, 4253.723507971972, 4290.892727681833, 4315.4605674290715, 4348.021695118573, 4351.651867850627, 4849.709017458964, 5524.696141712245, 5661.264035675418, 5730.426957304237, 5744.971275143597, 5443.747937974288, 6064.696765161724, 6173.32093469718, 6195.96902015496, 6207.441330280297, 5607.52336051432, 5609.719514024282, 5636.464535869086, 5649.745761440745, 5654.886902261932, 4456.353828694886, 4745.908441058576, 5060.2156665716875, 5236.423993360727, 5348.946510534923, 5033.270175824412, 5237.528622424, 6215.378462153749, 6234.0337247726675, 6257.40554647326, 6127.14220009208, 6142.324344322032, 6173.290071641819, 6194.442500175006, 6232.220972378453, 6108.251008765869, 6117.174181485442, 6126.2123341605975, 6175.62946223228, 6194.690265486798, 5018.071656743514, 6080.635161890301, 6091.604045737013, 6103.0, 6135.938640613536, 6115.480184184395, 6158.630482171084, 6161.260648722003, 6183.412575805291, 6186.1648677427975, 5798.1302804579655, 5805.8548536286935, 5861.179434879035, 5930.406959304035, 5983.222181116601, 5869.119632055226, 5910.261217347832, 5915.231019967377, 5921.052631578927, 5929.584929055098, 5649.830505084854, 5701.543876489954, 5797.594168408171, 5800.883982320414, 5813.651180929158, 5739.225204597493, 5838.386969368155, 5901.232839730979, 5904.173415721888, 5967.194428752063, 5788.450097240755, 5802.912956305743, 5840.503557197686, 5852.268466441695, 5866.178734977192], "value": 5515.889258718733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.7089319175515, 486.9196582563877, 511.42209303487056, 513.0636588226483, 536.6021513956144, 29321.706782932164, 31119.0, 31295.68704312956, 38697.03257418566, 38744.0, 40876.79561602188, 41348.17303653925, 41359.58640413595, 41392.586074139246, 41539.58460415395, 9177.678781242674, 9329.0, 9378.671746488873, 9381.484018378986, 9385.296102792283, 9345.76635584109, 9450.385724927892, 9458.716238512856, 9461.432314061143, 9463.57413916372, 8016.398770092237, 9381.202597779196, 9385.0, 9429.81140377192, 9516.857247141299, 9247.491387973692, 9409.341346105775, 9431.481268530228, 9459.337846350758, 9469.715908522687, 9253.444793312356, 9274.768130796716, 9364.625414983393, 9365.531723413802, 9372.484513351763, 38341.233175336616, 38347.84956451311, 38393.0, 38596.0, 38697.806510967544, 3151.43274210642, 3254.7721659483846, 3311.966880331214, 3378.0372593810894, 3684.452071194829, 22566.887165564232, 29646.110616681533, 29873.25316867074, 30297.848510757307, 30542.84728576343, 3104.2549788050933, 3140.309131990958, 3177.380410819891, 3211.2132527530903, 3218.09893229896, 473458.0, 478507.0, 478637.0, 479900.0, 479908.0, 53321.2001819973, 53704.194437083475, 53715.462845371825, 56727.43272567304, 57890.710546447, 60950.0, 61105.69447152736, 61453.0, 61560.76878462406, 61698.38301616926, 15894.52316430509, 16074.43739469113, 16115.033098014157, 16121.838781612269, 16128.596785080232, 10066.295359324777, 11103.500342484607, 11476.655700329004, 12415.751684966432, 12540.122191446693, 13544.18734875891, 15362.923185384225, 16076.356945722275, 16130.154344606846, 16204.918975405284, 3156.1162874395204, 6501.1548498694865, 6722.764703235461, 6727.260001399842, 6782.287859774653, 841.9915800842035, 927.1099744245465, 972.7811242470387, 976.3312131190157, 997.2470784557613, 21293.46766330823, 28294.86820527197, 29053.0, 29563.965261216184, 29598.0, 1023.6212601337404, 1044.472541366605, 1083.0, 1093.8796732359433, 1145.1983611472003, 14823.332950017275, 15263.847361526243, 15319.923400382926, 15386.846131538765, 15598.532044038697, 477308.0, 574877.0, 581182.0, 582689.0, 582932.0, 56446.717766410904, 56469.71765141148, 56807.0, 57017.14474282889, 57287.0, 1338.110156745753, 1363.3183408295852, 1371.2595198592649, 1373.0457332154256, 1374.415873253863, 27896.163115106578, 28702.138935831957, 28870.42259154847, 29278.268043298234, 29300.33745781777, 53276.73361633167, 54878.725606371714, 54951.90096198134, 58240.12639810491, 58318.7084064577, 594121.0, 616204.0, 623557.0, 624788.0, 625949.0, 40860.1827963445, 41416.171676566904, 41486.79256603698, 41794.955126120876, 42016.78991605023, 45248.0, 45442.54557454383, 45511.31733024074, 45895.541044589125, 45949.76288756794, 37615.05962350854, 38279.617203827605, 38421.6157838418, 39527.40708889312, 39568.208635826544, 36476.0, 36507.81746091253, 37292.0, 37604.81197593995, 38664.80667596644, 36412.0, 36635.45046824247, 37211.813940931206, 37558.62441375551, 38681.80659096799, 30267.394652106395, 31669.841650791597, 34266.31467370688, 34420.31159376748, 34494.82752586221, 19551.511212219808, 24995.000199991795, 25210.873945630156, 25256.873715631304, 25619.487610247317], "value": 19427.977788931137, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.7434052757793, 368.4767629965449, 374.1562775940254, 382.30420634445323, 397.84822937595663, 17978.91010544947, 18508.259669613217, 19069.14188861501, 19496.610067798654, 19716.901415492903, 19257.22971081155, 19404.223831046776, 19423.611527769437, 19574.608507829835, 19722.50693732654, 5542.944570554293, 5795.739191736382, 5796.768129274824, 5797.913031304533, 5801.100829371454, 5668.858278543049, 5968.1346204800275, 6013.669248191348, 6074.119252708355, 6079.908801367983, 5102.234664800272, 5422.0, 6009.4591486766185, 6010.0, 6028.3067447243475, 6504.447121994636, 6517.641529715864, 6626.834329141761, 6630.569013014138, 6646.833829154261, 5629.60592758507, 5842.152887831241, 5874.206982057453, 5900.55745819061, 5917.112433135021, 12746.80879786804, 12817.551385701548, 12994.0, 16121.0, 24182.637260441108, 2044.9795502044938, 2103.779103194158, 2146.4097373222335, 2313.6876521669697, 2951.719586639266, 9695.078967498146, 9785.0, 10334.896651033469, 12668.366581670973, 14457.204853733041, 1830.6979348407497, 1840.68708319586, 1858.3310008396966, 1869.6634605770955, 1893.091316168247, 124474.0, 125932.0, 126360.0, 127383.36308318492, 130437.0, 11331.546738130548, 11464.656060318204, 11709.770474100267, 11749.706257343463, 11842.11184161174, 14350.0, 14359.85640143606, 14369.425222990963, 14376.496822611158, 14427.35076921541, 6893.93106068943, 8205.261526462647, 8326.875096873551, 8345.95827020861, 8356.373272004625, 8210.234799518132, 8239.434507443566, 8379.37154713399, 8390.202930721694, 8393.412461127784, 4376.343548467756, 4457.353683715908, 4526.88682782933, 4529.8641040768825, 4566.13243483737, 1847.086293407527, 1914.4543805015783, 1922.7019811929174, 1931.1020375525447, 1958.8922609256342, 786.3159051625123, 787.8896954426385, 862.6930200745875, 988.614440368257, 1025.0620682075967, 19169.80830191708, 20874.06066727001, 20929.372118836458, 20964.266250681154, 21093.472663183544, 884.8672699095188, 890.2521881619471, 960.4621412009298, 970.8737864077625, 979.8775153105862, 9562.330636855353, 10306.536205870754, 10894.67315980522, 10920.726981825359, 10940.781184376268, 494572.0, 500922.0, 503444.0, 592658.0, 613906.0, 124255.0, 124565.0, 126279.73720262863, 138093.0, 139044.0, 934.4393363981693, 984.1683777208391, 1004.5128112865254, 1005.2460654509118, 1006.4565134827258, 18552.44342669722, 19041.333553325567, 19166.425007249803, 19256.518587035436, 19287.51781205481, 55372.72313638406, 56280.718596406754, 57577.0, 57646.7117664409, 58578.414215857294, 78165.60917195378, 79663.4067318662, 81260.59369703113, 83607.5819620898, 84695.0, 6051.3041000283765, 7848.018997625297, 7872.370210383041, 7935.2064793520185, 7942.523448593103, 11284.717882052686, 12182.695432614255, 12231.877681223074, 12397.194182378116, 12462.1276510647, 9906.752331191778, 10015.248856335658, 10620.20348473852, 10747.516361763583, 10866.13070954306, 10551.0, 10554.947225263824, 10594.576216951236, 10980.890191098306, 11072.667819965414, 8857.822843543221, 10837.945810270898, 10993.725156871142, 11189.216754827085, 11381.943090284496, 7880.2513761192595, 7947.403944704286, 8227.958860205661, 8243.546604936786, 8324.084350721294, 9610.327277090817, 10755.0, 10884.292520986111, 10922.071623912056, 11134.888651113386], "value": 9385.829183254498, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1197.485081414991, 1258.1444617659993, 1298.2405292903652, 1303.0032025500493, 1497.6031351691804, 7501.212372700869, 7755.806104847382, 8067.959660201698, 8509.149085091496, 8531.402801803877, 6749.2913244109395, 7976.561289129096, 8248.381371397154, 8355.623996920138, 8358.247757701805, 2084.208000959637, 2304.9193278235266, 2306.192832508617, 2310.2722642367685, 2313.8611683298973, 2316.8494047886834, 2355.5524450354465, 2357.49313897512, 2361.6929799126087, 2367.9289621311386, 2259.2318611672113, 2273.7498875123724, 2275.180934863448, 2294.27730264966, 2298.9310320690406, 2246.472079061419, 2265.0600000999566, 2285.1773361589812, 2288.645259984705, 2297.035245197013, 2319.52449747803, 2334.113037045912, 2355.7173139223232, 2355.740868504463, 2369.2536850891915, 8301.543415112166, 8335.333173346118, 8342.582870856493, 8350.206730360605, 8354.206350396702, 5035.471275516054, 5062.645614806928, 5199.974000130013, 5213.791448342062, 5242.135047717122, 3399.3031428557088, 3507.8070706111407, 3624.5288112545463, 3628.129248980252, 3633.4368172933237, 1055.6885718712997, 1068.8343306787383, 1073.6778966310076, 1105.5522513382093, 1107.1087774341652, 9102.089791020844, 10902.945485272603, 11006.669799906096, 11019.953104455064, 11038.17213708967, 4173.6661067114555, 4414.3157810539415, 4435.068635586498, 4493.820247190141, 4517.932231016537, 8504.914950850412, 8572.52851093196, 8589.012263589642, 8602.18279263469, 8606.483610983361, 5087.847364579068, 5089.491050894881, 5091.949080509222, 5097.796088156507, 5119.923201151985, 4229.746215227096, 4403.053343531156, 4561.608709343682, 4609.124266389375, 4766.237402015661, 2820.5205115130475, 2925.254060214674, 3425.092350527112, 3744.6255374462335, 3754.3992961126637, 1129.813580759182, 1153.9538418463335, 1160.7330314027652, 1162.7616338650557, 1164.8002557365926, 763.4007304266124, 793.4089103617727, 821.9342452603777, 884.3897710579679, 895.4582477600958, 4844.32179494871, 4897.8285759998225, 5388.649737767033, 5462.9726851365485, 5706.743196556165, 952.1620973543238, 965.0, 986.9605215791431, 1002.8395456726888, 1042.551702767802, 3738.6822120120096, 3832.770033797981, 3834.9616503835164, 3849.557300910431, 3868.419737039467, 8967.282617390592, 9626.65226868239, 9646.710598682052, 10178.745531361626, 10188.286819922683, 10101.646442374482, 10135.695929122137, 10142.847857282146, 10156.984301569784, 10166.186705063577, 7416.555006699616, 7737.651805668758, 7830.33442157423, 7861.882071768928, 7889.526628402199, 5623.662580245198, 5661.85845353853, 5833.591648584728, 5883.382244864228, 5889.911651325147, 9218.815623687355, 9266.351355405326, 9308.208802251884, 9408.012158723235, 9550.570224339783, 7083.610401427971, 7716.344110750649, 9412.717618471457, 9584.616615335588, 9628.133467987915, 6606.6036037837885, 6834.316568343126, 7144.928550714426, 7280.672369743268, 7286.307800758919, 7708.49894756839, 7925.207479252259, 7943.364530837403, 8058.194180581895, 8059.43583949118, 6873.828154296182, 7062.576245425291, 7227.060482137288, 7231.710731570678, 7318.597477138808, 6997.0, 7061.964690176722, 7091.219965803654, 7102.538335008208, 7107.395871350786, 6437.452816510649, 6635.502337324623, 6713.899291510729, 6745.763898263537, 6773.661316934232, 4799.784009719641, 4810.831620893252, 4834.951650483591, 4847.466778654275, 4878.390201224847, 4745.1221524018165, 4775.976120119377, 4796.424429068395, 4911.410630724193, 4974.00519896015], "value": 4332.6163159325515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.53431304035297, 289.61336615618137, 305.72332039504226, 305.80734137493386, 309.0157847256488, 5357.464253574641, 5484.561235101192, 5485.259489968853, 5580.302462192226, 5582.692951887645, 5090.78550830389, 5316.441773613773, 5453.890922181554, 5495.780168793243, 5528.917066243999, 3310.940499040314, 3853.2100919311474, 4193.832246710143, 4216.219999300124, 4220.36694495825, 3453.4474484082552, 3677.8896633100917, 3818.599047100056, 4031.516218053823, 4229.450171477703, 3594.748367614268, 3764.717646176534, 4103.446034785311, 4109.342505199169, 4121.237571049365, 3455.3953058214965, 3669.8899033028783, 3710.1466662667603, 3775.5275442577417, 3911.86555898789, 3372.5615669962995, 3574.267275208602, 3583.7312201584987, 3588.3361578107874, 3590.874319398835, 5137.74311284438, 5357.4106848246665, 5379.704116273642, 5385.569154467633, 5407.215953686733, 3811.4663947047156, 4319.50325712544, 4569.314602809572, 4692.4603670577635, 4715.339852420664, 4985.027919555688, 5461.2627295315415, 5634.605577609569, 5771.913421298684, 5830.32951210612, 3456.3087382523595, 3780.9621903781162, 3901.6878649707955, 3926.2540117377894, 3943.507061617298, 5290.285811415448, 6006.279246490449, 6025.849353766147, 6035.245594300713, 6044.491536012593, 3939.9212015759526, 3974.940375894364, 3985.0074962518743, 3996.7202295839593, 3997.4803275574573, 3850.845966161322, 3986.242613903349, 4040.4949381327333, 4050.5746896576024, 4060.4315395844606, 4101.343784994387, 4249.532551419341, 4279.700420970565, 4445.955540444619, 4686.156491831503, 4899.951000489949, 5093.567046800989, 5386.326709161355, 5822.679752613562, 5868.383819698955, 1240.5906051003146, 1265.993670031644, 1280.564608033273, 1288.865798097668, 1319.399673148715, 851.7827953871723, 866.7703058689452, 886.902440731519, 888.7689200807707, 890.3100097424418, 4874.488178741181, 5269.657472264291, 5307.734613269321, 5315.813946511854, 5319.946800532022, 4952.851414457572, 5231.450697676765, 5725.599208055396, 5754.223179870663, 5779.393163717833, 4765.618750499952, 4854.805807767721, 5003.399592048978, 5258.8948221036135, 5279.868003299872, 2587.327294903301, 2666.706662267149, 2699.757021868041, 2848.9031722786876, 2964.614600101973, 5507.284053073155, 5839.854003649858, 5870.385643947863, 5887.643144073355, 5892.0, 5073.974630126826, 5362.383325917492, 5635.266376243975, 5696.857578560486, 5815.592908496449, 5354.330708661418, 5413.9427017733005, 5842.211301474332, 5849.912251316234, 5876.6777475067765, 5173.586113110867, 5249.921251181159, 5691.069099413722, 5727.8568035799435, 5756.158029430542, 5193.0, 5438.918416223839, 5602.1512900741845, 5603.943960560342, 5632.690202038927, 5014.87462813418, 5592.832215033555, 5863.0032894408905, 5886.499647530008, 5886.823395298148, 3265.9510107348883, 3550.0, 3647.908802279964, 3757.0982964087875, 3774.1885494618787, 5044.798208071636, 5381.650192737459, 5432.483914028161, 5583.888322233452, 5645.181448689835, 4732.834350797705, 5735.719387270931, 5897.085951677497, 5897.3807750185615, 5926.555508336805, 5160.845174644766, 5849.824505264849, 5879.853003674943, 5899.174115623902, 5911.674857882857, 5123.282740416419, 5554.361248456479, 5688.886222275614, 5748.252727145612, 5766.596338256281, 4465.843695470643, 5614.88373952444, 5809.5352371811, 5868.354481007171, 5869.236999190249, 5051.646384753032, 5141.015712822593, 5496.0, 5619.859503512282, 5756.597038207286], "value": 4095.7855915927967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.7343568829715, 692.2661978302999, 712.1489819665495, 756.5498528375618, 794.2930791595481, 13835.58493245203, 14426.855731442682, 14773.704525909476, 14879.776803347944, 14997.62505937352, 13285.867141328585, 13346.399412026458, 13575.796363054538, 13601.319934003284, 13630.318484075806, 800.2077942836579, 813.0609146435876, 813.8372325534887, 814.0800894988655, 814.5723495165026, 648.9221293444793, 670.4468813229081, 764.9770506884803, 780.3601047141346, 792.825578372757, 867.5402036920442, 867.8437881181385, 867.8828358171661, 870.6299822575412, 872.7599910024762, 854.5556310718423, 862.6808081010035, 863.4603372891943, 863.96976105836, 865.2947847504296, 611.2237458427816, 617.7559863853797, 629.889769290377, 632.1339764522634, 633.2053273142224, 13745.931270343684, 13764.518241861486, 13849.446022159102, 13865.306734663327, 13890.861091389159, 12297.815532767016, 12485.188462749893, 12509.874901250962, 12577.245365278113, 12644.431000604904, 10893.564257429693, 11301.49143288554, 11305.604303849408, 11342.319460832294, 11355.659330220107, 3530.75284730069, 3560.2523470071314, 3560.3413368526644, 3584.713222942158, 3697.907552311187, 70755.0, 89532.99999999935, 93844.0, 95026.04973950241, 96098.0, 9938.304318697621, 10051.798964020678, 10376.533056012497, 10473.84289235662, 10535.683929482127, 19878.0, 19880.90059549722, 19922.90038549827, 19923.501912452306, 19926.50183745389, 7288.344049035506, 7423.146338171072, 7506.962465187748, 7620.390368770485, 7657.234276572298, 4154.065335299594, 4194.74157752673, 4213.304804707189, 4251.638610718123, 4273.529911709709, 3272.149241197306, 3371.477420999749, 3391.101358140095, 3606.441001644749, 3754.7747135171976, 615.7807541068709, 660.3693472733528, 662.1359126340172, 664.6676661669165, 683.0505597219868, 487.529533999692, 569.6012791046285, 622.4273668225244, 636.2905360523076, 654.326044174501, 7901.367890568741, 8618.827623447622, 8645.827083458422, 8870.778230544289, 9136.26909847224, 501.59872102318, 503.67764630636424, 505.1765622036058, 576.8990426675399, 586.7037146241121, 4241.491021077491, 4244.808983595745, 4445.466544014675, 4514.954850451453, 4541.909161816745, 105196.0, 106440.46779766052, 108697.0, 110181.0, 110960.0, 59247.40752592505, 63636.68181659062, 88258.67611985825, 88832.0, 93711.0, 62786.68606657029, 82144.0, 83902.0, 84326.0, 86503.56748216345, 8539.74380768578, 8582.227599516074, 8666.913330866862, 8719.084496127814, 8752.912470875464, 53858.730706346214, 54025.45974540361, 54554.72722636361, 55062.724686376314, 56118.158227625805, 76883.61558192174, 77461.61269193844, 78679.21320786948, 78911.0, 79411.0, 10964.616238431936, 11184.0, 11202.935721106482, 11297.0, 11478.655640330804, 11983.700407489883, 12284.1401101922, 13128.212307261212, 13698.794518082037, 13926.93036534811, 13066.346682666019, 13372.665683357995, 13377.933110334387, 13711.314434278047, 13801.930990344985, 12698.619041428772, 13431.992600554802, 13497.392617332047, 13523.526676566273, 13560.186388816308, 11969.222000569935, 12872.613821585368, 13158.0, 13378.331083445593, 13552.32238388057, 10217.642382516577, 10739.516721747385, 11024.118070554498, 11183.776324473627, 11313.943430282796, 10178.898211017795, 10217.693469195936, 10218.335808172445, 11048.558057677603, 11105.888941110485], "value": 7094.881850927703, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1637.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 117.0, 139.0, 180.0, 117.0, 118.0, 120.0, 131.0, 177.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 130.0, 105.0, 149.0, 122.0, 144.0, 104.0, 131.0, 178.0, 137.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 198.0, 199.0, 197.0, 198.0, 277.0, 238.0, 195.0, 201.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 65.0, 83.0, 111.0, 88.0, 59.0, 61.0, 70.0, 79.0, 55.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 92.0, 71.0, 69.0, 71.0, 105.0, 69.0, 59.0, 116.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 102.0, 87.0, 131.0, 130.0, 88.0, 94.0, 90.0, 106.0, 113.0], "value": 106.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 127.0, 154.0, 113.0, 96.0, 96.0, 97.0, 96.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 93.0, 139.0, 74.0, 139.0, 129.0, 140.0, 131.0, 138.0], "value": 116.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 56.0, 59.0, 62.0, 53.0, 56.0, 68.0, 51.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 111.0, 114.0, 126.0, 79.0, 71.0, 86.0, 106.0, 108.0, 73.0], "value": 96.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 103.0, 105.0, 105.0, 85.0, 77.0, 100.0, 105.0, 83.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 149.0, 162.0, 163.0, 159.0, 162.0, 168.0, 163.0, 198.0, 153.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 168.0, 173.0, 178.0, 235.0, 171.0, 218.0, 160.0, 229.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 70.0, 71.0, 81.0, 87.0, 82.0, 70.0, 68.0, 69.0], "value": 73.7, "unit": "score"}]}]} 02:45:26 INFO - Return code: 0 02:45:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:45:26 ERROR - # TBPL SUCCESS # 02:45:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:26 INFO - 2016-12-06 02:45:26.321 screenresolution[2555:39961] starting screenresolution argv=screenresolution get 02:45:26 INFO - 2016-12-06 02:45:26.323 screenresolution[2555:39961] Display 0: 1600x1200x32@60 02:45:26 INFO - 2016-12-06 02:45:26.330 screenresolution[2556:39964] starting screenresolution argv=screenresolution list 02:45:26 INFO - Available Modes on Display 0 02:45:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:26 INFO - Intel Iris: 02:45:26 INFO - Chipset Model: Intel Iris 02:45:26 INFO - Type: GPU 02:45:26 INFO - Bus: Built-In 02:45:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:26 INFO - Vendor: Intel (0x8086) 02:45:26 INFO - Device ID: 0x0a2e 02:45:26 INFO - Revision ID: 0x0009 02:45:26 INFO - Displays: 02:45:26 INFO - Display: 02:45:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:26 INFO - Display Serial Number: 1600x1200 60 02:45:26 INFO - Main Display: Yes 02:45:26 INFO - Mirror: Off 02:45:26 INFO - Online: Yes 02:45:26 INFO - Rotation: Supported 02:45:26 INFO - Return code: 0 02:45:26 INFO - Running post-action listener: _resource_record_post_action 02:45:26 INFO - [mozharness: 2016-12-06 10:45:26.579101Z] Finished run-tests step (success) 02:45:26 INFO - Running post-run listener: _resource_record_post_run 02:45:26 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 970752; Write bytes: 662629888; Read time: 10; Write time: 1904 02:45:26 INFO - TinderboxPrint: CPU usage
54.5% 02:45:26 INFO - TinderboxPrint: I/O read bytes / time
970,752 / 10 02:45:26 INFO - TinderboxPrint: I/O write bytes / time
662,629,888 / 1,904 02:45:26 INFO - TinderboxPrint: CPU idle
2,205.9 (45.5%) 02:45:26 INFO - TinderboxPrint: CPU system
983.8 (20.3%) 02:45:26 INFO - TinderboxPrint: CPU user
1,659.9 (34.2%) 02:45:26 INFO - TinderboxPrint: Swap in / out
348,913,664 / 0 02:45:26 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 349278720; Write bytes: 350791168; Read time: 27685; Write time: 675 02:45:26 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 884736; Write bytes: 307441664; Read time: 9; Write time: 1207 02:45:27 INFO - Running post-run listener: _upload_blobber_files 02:45:27 INFO - Blob upload gear active. 02:45:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:45:27 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 02:45:27 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'] 02:45:27 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 02:45:27 INFO - (blobuploader) - INFO - Open directory for files ... 02:45:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:45:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:28 INFO - (blobuploader) - INFO - Done attempting. 02:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:29 INFO - (blobuploader) - INFO - Done attempting. 02:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:45:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:31 INFO - (blobuploader) - INFO - Done attempting. 02:45:31 INFO - (blobuploader) - INFO - Iteration through files over. 02:45:31 INFO - Return code: 0 02:45:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:45:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:45:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a7aafd396b63f87569e25dbf38c89b63a025af118348fecaf35bcbc99582dbe283c551bdba0ea2fb89c1c0059c133231a860e7450d3586f93db5f2fa3d2a934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23d8bf3dabd3fbf492b3e0c66314aad1c280bb202678f460966fe2dccfc9689d56ddc32fee905aa7da4b4d054ff8a4f3af710e38ce2a41a412994d6eeebdd3e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb08b89e2cc922952ca7f90887a078b7f21bf7cb048ecb90a9a254c9e971fa1e36927cabe6f909d08d4f30784fde62bc1e09060287533d5f87193bf3d67569a"} 02:45:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:45:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:45:31 INFO - Contents: 02:45:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a7aafd396b63f87569e25dbf38c89b63a025af118348fecaf35bcbc99582dbe283c551bdba0ea2fb89c1c0059c133231a860e7450d3586f93db5f2fa3d2a934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23d8bf3dabd3fbf492b3e0c66314aad1c280bb202678f460966fe2dccfc9689d56ddc32fee905aa7da4b4d054ff8a4f3af710e38ce2a41a412994d6eeebdd3e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb08b89e2cc922952ca7f90887a078b7f21bf7cb048ecb90a9a254c9e971fa1e36927cabe6f909d08d4f30784fde62bc1e09060287533d5f87193bf3d67569a"} 02:45:31 INFO - Running post-run listener: copy_logs_to_upload_dir 02:45:31 INFO - Copying logs to upload dir... 02:45:31 INFO - mkdir: /builds/slave/test/build/upload/logs 02:45:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.389820 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-06 02:45:31.871039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:31.874697) ========= 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.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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/3a7aafd396b63f87569e25dbf38c89b63a025af118348fecaf35bcbc99582dbe283c551bdba0ea2fb89c1c0059c133231a860e7450d3586f93db5f2fa3d2a934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23d8bf3dabd3fbf492b3e0c66314aad1c280bb202678f460966fe2dccfc9689d56ddc32fee905aa7da4b4d054ff8a4f3af710e38ce2a41a412994d6eeebdd3e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb08b89e2cc922952ca7f90887a078b7f21bf7cb048ecb90a9a254c9e971fa1e36927cabe6f909d08d4f30784fde62bc1e09060287533d5f87193bf3d67569a"} build_url:https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/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/3a7aafd396b63f87569e25dbf38c89b63a025af118348fecaf35bcbc99582dbe283c551bdba0ea2fb89c1c0059c133231a860e7450d3586f93db5f2fa3d2a934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23d8bf3dabd3fbf492b3e0c66314aad1c280bb202678f460966fe2dccfc9689d56ddc32fee905aa7da4b4d054ff8a4f3af710e38ce2a41a412994d6eeebdd3e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb08b89e2cc922952ca7f90887a078b7f21bf7cb048ecb90a9a254c9e971fa1e36927cabe6f909d08d4f30784fde62bc1e09060287533d5f87193bf3d67569a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:31.909806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:31.910188) ========= 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.tE5wERhgxU/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.mLFlL20FzY/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:31.959019) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 02:45:31.959338) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 02:45:31.959694) ========= ========= Total master_lag: 0.20 =========