builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0308 starttime: 1476859334.79 results: success (0) buildid: 20161018195923 builduid: 3526de9da3f441f2bf1608e295abe08f revision: f52b380bc02e1022631ab8dcd43ed246930974c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:14.791522) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:14.791954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:14.792276) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:14.817400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:14.817781) ========= 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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-18 23:42:14-- 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% 168M=0s 2016-10-18 23:42:15 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.639392 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:15.478568) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:15.478945) ========= 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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.023957 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:15.550912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:42:15.551682) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f52b380bc02e1022631ab8dcd43ed246930974c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f52b380bc02e1022631ab8dcd43ed246930974c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-18 23:42:15,640 truncating revision to first 12 chars 2016-10-18 23:42:15,640 Setting DEBUG logging. 2016-10-18 23:42:15,640 attempt 1/10 2016-10-18 23:42:15,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f52b380bc02e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 23:42:16,779 unpacking tar archive at: fx-team-f52b380bc02e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.416704 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:42:16.996489) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:16.997066) ========= script_repo_revision: f52b380bc02e1022631ab8dcd43ed246930974c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:16.997737) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:16.998828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 23:42:17.017568) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-18 23:42:17.017898) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42:17 INFO - MultiFileLogger online at 20161018 23:42:17 in /builds/slave/test 23:42:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:42:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:42:17 INFO - {'append_to_log': False, 23:42:17 INFO - 'base_work_dir': '/builds/slave/test', 23:42:17 INFO - 'blob_upload_branch': 'Fx-Team', 23:42:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:42:17 INFO - 'branch': 'Fx-Team', 23:42:17 INFO - 'buildbot_json_path': 'buildprops.json', 23:42:17 INFO - 'config_files': ('talos/mac_config.py',), 23:42:17 INFO - 'default_actions': ('clobber', 23:42:17 INFO - 'read-buildbot-config', 23:42:17 INFO - 'download-and-extract', 23:42:17 INFO - 'populate-webroot', 23:42:17 INFO - 'create-virtualenv', 23:42:17 INFO - 'install', 23:42:17 INFO - 'run-tests'), 23:42:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:42:17 INFO - 'download_minidump_stackwalk': True, 23:42:17 INFO - 'download_symbols': 'ondemand', 23:42:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:42:17 INFO - 'tooltool.py': '/tools/tooltool.py', 23:42:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:42:17 INFO - '/tools/misc-python/virtualenv.py')}, 23:42:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:42:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:42:17 INFO - 'installer_path': 'installer.exe', 23:42:17 INFO - 'log_level': 'info', 23:42:17 INFO - 'log_name': 'talos', 23:42:17 INFO - 'log_to_console': True, 23:42:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:42:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:42:17 INFO - 'opt_config_files': (), 23:42:17 INFO - 'pip_index': False, 23:42:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:42:17 INFO - 'cmd': ('bash', 23:42:17 INFO - '-c', 23:42:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:42:17 INFO - 'enabled': True, 23:42:17 INFO - 'halt_on_failure': False, 23:42:17 INFO - 'name': 'check_screen_resolution'},), 23:42:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:42:17 INFO - 'cmd': ('bash', 23:42:17 INFO - '-c', 23:42:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:42:17 INFO - 'enabled': True, 23:42:17 INFO - 'halt_on_failure': False, 23:42:17 INFO - 'name': 'check_screen_resolution'},), 23:42:17 INFO - 'run_cmd_checks_enabled': True, 23:42:17 INFO - 'sps_profile': False, 23:42:17 INFO - 'sps_profile_interval': 0, 23:42:17 INFO - 'suite': 'dromaeojs-e10s', 23:42:17 INFO - 'system_bits': '32', 23:42:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:42:17 INFO - 'title': 't-yosemite-r7-0308', 23:42:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:42:17 INFO - 'use_talos_json': True, 23:42:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:42:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:42:17 INFO - 'work_dir': 'build'} 23:42:17 INFO - [mozharness: 2016-10-19 06:42:17.227622Z] Running clobber step. 23:42:17 INFO - Running pre-action listener: _resource_record_pre_action 23:42:17 INFO - Running main action method: clobber 23:42:17 INFO - rmtree: /builds/slave/test/build 23:42:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:42:19 INFO - Running post-action listener: _resource_record_post_action 23:42:19 INFO - [mozharness: 2016-10-19 06:42:19.297327Z] Finished clobber step (success) 23:42:19 INFO - [mozharness: 2016-10-19 06:42:19.297444Z] Running read-buildbot-config step. 23:42:19 INFO - Running pre-action listener: _resource_record_pre_action 23:42:19 INFO - Running main action method: read_buildbot_config 23:42:19 INFO - Using buildbot properties: 23:42:19 INFO - { 23:42:19 INFO - "project": "", 23:42:19 INFO - "product": "firefox", 23:42:19 INFO - "script_repo_revision": "production", 23:42:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 23:42:19 INFO - "repository": "", 23:42:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 23:42:19 INFO - "buildid": "20161018195923", 23:42:19 INFO - "pgo_build": "False", 23:42:19 INFO - "basedir": "/builds/slave/test", 23:42:19 INFO - "buildnumber": 110, 23:42:19 INFO - "slavename": "t-yosemite-r7-0308", 23:42:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:42:19 INFO - "platform": "yosemite_r7", 23:42:19 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 23:42:19 INFO - "branch": "fx-team", 23:42:19 INFO - "repo_path": "integration/fx-team", 23:42:19 INFO - "revision": "f52b380bc02e1022631ab8dcd43ed246930974c3", 23:42:19 INFO - "stage_platform": "macosx64", 23:42:19 INFO - "builduid": "3526de9da3f441f2bf1608e295abe08f", 23:42:19 INFO - "slavebuilddir": "test" 23:42:19 INFO - } 23:42:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 23:42:19 INFO - Running post-action listener: _resource_record_post_action 23:42:19 INFO - [mozharness: 2016-10-19 06:42:19.300884Z] Finished read-buildbot-config step (success) 23:42:19 INFO - [mozharness: 2016-10-19 06:42:19.301016Z] Running download-and-extract step. 23:42:19 INFO - Running pre-action listener: _resource_record_pre_action 23:42:19 INFO - Running main action method: download_and_extract 23:42:19 INFO - mkdir: /builds/slave/test/build/tests 23:42:19 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')]} 23:42:19 INFO - trying https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 23:42:19 INFO - Downloading https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 23:42:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 23:42:20 INFO - Downloaded 1199 bytes. 23:42:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 23:42:20 INFO - Using the following test package requirements: 23:42:20 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:42:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 23:42:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 23:42:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'jsshell-mac.zip'], 23:42:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 23:42:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:42:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 23:42:20 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 23:42:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 23:42:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:42:20 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 23:42:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:42:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:42:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:42:20 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:42:20 INFO - Http code: 200 23:42:20 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 23:42:20 INFO - x-amz-cf-id: AJpkrWtEaUqGtU_sfz8qV__2JsZCqIPvJAwH-k1giEfoI0QCwWI2qQ== 23:42:20 INFO - x-amz-version-id: XHurmWQKcwP2D0ZVKCXFcZ3uEetYXPD3 23:42:21 INFO - Expected file size: 16500000 23:42:21 INFO - Obtained file size: 16500000 23:42:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:42:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:42:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:42:28 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:42:28 INFO - Http code: 200 23:42:28 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 23:42:28 INFO - x-amz-cf-id: YbZYZpdw51uZdnaML6cXPCFWspbDGK-aihdvPrTwkct5SzP8vEnlFw== 23:42:28 INFO - x-amz-version-id: XHurmWQKcwP2D0ZVKCXFcZ3uEetYXPD3 23:42:28 INFO - Expected file size: 16500000 23:42:28 INFO - Obtained file size: 16500000 23:42:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 23:42:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:42:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 23:42:35 INFO - Http code: 200 23:42:35 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 23:42:35 INFO - x-amz-cf-id: s4qhvhk362LeC5bnhj5UvV84FYvXiyVANNQrhMpqMOOPZFGGsSOfxA== 23:42:35 INFO - x-amz-version-id: V3G5TxVXEKPvoF7zv8g2uxR_Xq1IlOlP 23:42:36 INFO - Expected file size: 14409028 23:42:36 INFO - Obtained file size: 14409028 23:42:36 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')]} 23:42:36 INFO - trying https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:42:36 INFO - Downloading https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 23:42:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 23:42:39 INFO - Downloaded 111645606 bytes. 23:42:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:42:39 INFO - mkdir: /builds/slave/test/properties 23:42:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:42:39 INFO - Writing to file /builds/slave/test/properties/build_url 23:42:39 INFO - Contents: 23:42:39 INFO - build_url:https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:42:40 INFO - Running post-action listener: _resource_record_post_action 23:42:40 INFO - Running post-action listener: set_extra_try_arguments 23:42:40 INFO - [mozharness: 2016-10-19 06:42:40.548229Z] Finished download-and-extract step (success) 23:42:40 INFO - [mozharness: 2016-10-19 06:42:40.548349Z] Running populate-webroot step. 23:42:40 INFO - Running pre-action listener: _resource_record_pre_action 23:42:40 INFO - Running main action method: populate_webroot 23:42:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:42:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:42:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:42:40 INFO - '--tppagecycles', 23:42:40 INFO - '7'], 23:42:40 INFO - 'tests': ['tsvgm']}}, 23:42:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:42:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:42:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:42:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:42:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['damp', 'tps']}, 23:42:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'tests': ['damp', 'tps']}, 23:42:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['dromaeo_dom']}, 23:42:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:42:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['basic_compositor_video']}, 23:42:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:42:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['a11yr', 23:42:40 INFO - 'ts_paint', 23:42:40 INFO - 'tpaint', 23:42:40 INFO - 'sessionrestore', 23:42:40 INFO - 'sessionrestore_no_auto_restore', 23:42:40 INFO - 'tabpaint']}, 23:42:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tsvgx', 23:42:40 INFO - 'tsvgr_opacity', 23:42:40 INFO - 'tart', 23:42:40 INFO - 'tscrollx', 23:42:40 INFO - 'cart']}, 23:42:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:42:40 INFO - 'tsvgr_opacity', 23:42:40 INFO - 'tart', 23:42:40 INFO - 'tscrollx', 23:42:40 INFO - 'cart']}, 23:42:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s'], 23:42:40 INFO - 'tests': ['tp5o']}, 23:42:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'tests': ['tp5o']}, 23:42:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'talos_options': ['--disable-e10s', 23:42:40 INFO - '--xperf_path', 23:42:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:40 INFO - 'tests': ['tp5n']}, 23:42:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:42:40 INFO - 'talos_options': ['--xperf_path', 23:42:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:40 INFO - 'tests': ['tp5n']}}, 23:42:40 INFO - 'talos.zip': {'path': '', 23:42:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:42:40 INFO - Running post-action listener: _resource_record_post_action 23:42:40 INFO - [mozharness: 2016-10-19 06:42:40.567741Z] Finished populate-webroot step (success) 23:42:40 INFO - [mozharness: 2016-10-19 06:42:40.567831Z] Running create-virtualenv step. 23:42:40 INFO - Running pre-action listener: _resource_record_pre_action 23:42:40 INFO - Running main action method: create_virtualenv 23:42:40 INFO - Creating virtualenv /builds/slave/test/build/venv 23:42:40 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 23:42:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:42:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:42:40 INFO - Using real prefix '/tools/python27' 23:42:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:42:41 INFO - Installing distribute.............................................................................................................................................................................................done. 23:42:44 INFO - Installing pip.................done. 23:42:44 INFO - Return code: 0 23:42:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:42:44 INFO - Reading from file tmpfile_stdout 23:42:44 INFO - Output received: 23:42:44 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) 23:42:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:44 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42:44 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 23:42:44 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 23:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:44 INFO - 'HOME': '/Users/cltbld', 23:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:44 INFO - 'LOGNAME': 'cltbld', 23:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:44 INFO - 'MOZ_NO_REMOTE': '1', 23:42:44 INFO - 'NO_EM_RESTART': '1', 23:42:44 INFO - 'PAGER': '/bin/cat', 23:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:44 INFO - 'PWD': '/builds/slave/test', 23:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:44 INFO - 'SHELL': '/bin/bash', 23:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:44 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:44 INFO - 'USER': 'cltbld', 23:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:44 INFO - 'XPC_FLAGS': '0x0', 23:42:44 INFO - 'XPC_SERVICE_NAME': '0', 23:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:44 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 23:42:44 INFO - Cleaning up... 23:42:44 INFO - Return code: 0 23:42:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:44 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42:44 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 23:42:44 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 23:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:44 INFO - 'HOME': '/Users/cltbld', 23:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:44 INFO - 'LOGNAME': 'cltbld', 23:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:44 INFO - 'MOZ_NO_REMOTE': '1', 23:42:44 INFO - 'NO_EM_RESTART': '1', 23:42:44 INFO - 'PAGER': '/bin/cat', 23:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:44 INFO - 'PWD': '/builds/slave/test', 23:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:44 INFO - 'SHELL': '/bin/bash', 23:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:44 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:44 INFO - 'USER': 'cltbld', 23:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:44 INFO - 'XPC_FLAGS': '0x0', 23:42:44 INFO - 'XPC_SERVICE_NAME': '0', 23:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:45 INFO - Downloading/unpacking psutil>=3.1.1 23:42:45 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 23:42:45 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 23:42:45 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 23:42:45 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 23:42:45 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 23:42:45 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 23:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:49 INFO - Installing collected packages: psutil 23:42:49 INFO - Running setup.py install for psutil 23:42:49 INFO - building 'psutil._psutil_osx' extension 23:42:49 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 23:42:49 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 23:42:49 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 23:42:49 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 23:42:49 INFO - building 'psutil._psutil_posix' extension 23:42:49 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 23:42:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:42:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:42:49 INFO - ^ 23:42:50 INFO - 1 warning generated. 23:42: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 23:42:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:50 INFO - Successfully installed psutil 23:42:50 INFO - Cleaning up... 23:42:50 INFO - Return code: 0 23:42:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:42: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')]} 23:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42: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')]} 23:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42: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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42: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 23:42: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 23:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:50 INFO - 'HOME': '/Users/cltbld', 23:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:50 INFO - 'LOGNAME': 'cltbld', 23:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:50 INFO - 'MOZ_NO_REMOTE': '1', 23:42:50 INFO - 'NO_EM_RESTART': '1', 23:42:50 INFO - 'PAGER': '/bin/cat', 23:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:50 INFO - 'PWD': '/builds/slave/test', 23:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:50 INFO - 'SHELL': '/bin/bash', 23:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:50 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:50 INFO - 'USER': 'cltbld', 23:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:50 INFO - 'XPC_FLAGS': '0x0', 23:42:50 INFO - 'XPC_SERVICE_NAME': '0', 23:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:42:50 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 23:42:50 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 23:42:50 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 23:42:50 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 23:42:50 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 23:42:50 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 23:42:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:42:53 INFO - Installing collected packages: mozsystemmonitor 23:42:53 INFO - Running setup.py install for mozsystemmonitor 23:42:53 INFO - Successfully installed mozsystemmonitor 23:42:53 INFO - Cleaning up... 23:42:53 INFO - Return code: 0 23:42:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:42:53 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')]} 23:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:53 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')]} 23:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:53 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42:53 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 23:42:53 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 23:42:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:53 INFO - 'HOME': '/Users/cltbld', 23:42:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:53 INFO - 'LOGNAME': 'cltbld', 23:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:53 INFO - 'MOZ_NO_REMOTE': '1', 23:42:53 INFO - 'NO_EM_RESTART': '1', 23:42:53 INFO - 'PAGER': '/bin/cat', 23:42:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:53 INFO - 'PWD': '/builds/slave/test', 23:42:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:53 INFO - 'SHELL': '/bin/bash', 23:42:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:53 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:53 INFO - 'USER': 'cltbld', 23:42:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:53 INFO - 'XPC_FLAGS': '0x0', 23:42:53 INFO - 'XPC_SERVICE_NAME': '0', 23:42:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:53 INFO - Downloading/unpacking jsonschema==2.5.1 23:42:53 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 23:42:53 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 23:42:53 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 23:42:53 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 23:42:53 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 23:42:53 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 23:42:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:42:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:42:56 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 23:42:56 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 23:42:56 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 23:42:56 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 23:42:56 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 23:42:56 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 23:42:56 INFO - Downloading functools32-3.2.3-2.tar.gz 23:42:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:42:56 INFO - warning: no files found matching '*.txt' 23:42:56 INFO - no previously-included directories found matching 'build' 23:42:56 INFO - no previously-included directories found matching 'dist' 23:42:56 INFO - no previously-included directories found matching '.git*' 23:42:56 INFO - Installing collected packages: jsonschema, functools32 23:42:56 INFO - Running setup.py install for functools32 23:42:56 INFO - warning: no files found matching '*.txt' 23:42:56 INFO - no previously-included directories found matching 'build' 23:42:56 INFO - no previously-included directories found matching 'dist' 23:42:56 INFO - no previously-included directories found matching '.git*' 23:42:56 INFO - Successfully installed jsonschema functools32 23:42:56 INFO - Cleaning up... 23:42:56 INFO - Return code: 0 23:42:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:42:56 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')]} 23:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:56 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')]} 23:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:56 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42:56 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 23:42:56 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 23:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:56 INFO - 'HOME': '/Users/cltbld', 23:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:56 INFO - 'LOGNAME': 'cltbld', 23:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:56 INFO - 'MOZ_NO_REMOTE': '1', 23:42:56 INFO - 'NO_EM_RESTART': '1', 23:42:56 INFO - 'PAGER': '/bin/cat', 23:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:56 INFO - 'PWD': '/builds/slave/test', 23:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:56 INFO - 'SHELL': '/bin/bash', 23:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:56 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:56 INFO - 'USER': 'cltbld', 23:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:56 INFO - 'XPC_FLAGS': '0x0', 23:42:56 INFO - 'XPC_SERVICE_NAME': '0', 23:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:42:57 INFO - Cleaning up... 23:42:57 INFO - Return code: 0 23:42:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:42: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')]} 23:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42: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')]} 23:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42: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', '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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:42: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:42: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 blobuploader==1.2.4 23:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:57 INFO - 'HOME': '/Users/cltbld', 23:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:57 INFO - 'LOGNAME': 'cltbld', 23:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:57 INFO - 'MOZ_NO_REMOTE': '1', 23:42:57 INFO - 'NO_EM_RESTART': '1', 23:42:57 INFO - 'PAGER': '/bin/cat', 23:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:57 INFO - 'PWD': '/builds/slave/test', 23:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:57 INFO - 'SHELL': '/bin/bash', 23:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:42:57 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:57 INFO - 'USER': 'cltbld', 23:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:57 INFO - 'XPC_FLAGS': '0x0', 23:42:57 INFO - 'XPC_SERVICE_NAME': '0', 23:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:57 INFO - Downloading/unpacking blobuploader==1.2.4 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42:59 INFO - Downloading blobuploader-1.2.4.tar.gz 23:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:43:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:43:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:01 INFO - Downloading docopt-0.6.1.tar.gz 23:43:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:43:01 INFO - Installing collected packages: blobuploader, requests, docopt 23:43:01 INFO - Running setup.py install for blobuploader 23:43:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:43:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:43:01 INFO - Running setup.py install for requests 23:43:01 INFO - Running setup.py install for docopt 23:43:02 INFO - Successfully installed blobuploader requests docopt 23:43:02 INFO - Cleaning up... 23:43:02 INFO - Return code: 0 23:43:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:43:02 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')]} 23:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:02 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')]} 23:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:02 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:43:02 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 23:43:02 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 23:43:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:43:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:43:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:43:02 INFO - 'HOME': '/Users/cltbld', 23:43:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:43:02 INFO - 'LOGNAME': 'cltbld', 23:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:02 INFO - 'MOZ_NO_REMOTE': '1', 23:43:02 INFO - 'NO_EM_RESTART': '1', 23:43:02 INFO - 'PAGER': '/bin/cat', 23:43:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:02 INFO - 'PWD': '/builds/slave/test', 23:43:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:43:02 INFO - 'SHELL': '/bin/bash', 23:43:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:43:02 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:43:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:43:02 INFO - 'USER': 'cltbld', 23:43:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:43:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:02 INFO - 'XPC_FLAGS': '0x0', 23:43:02 INFO - 'XPC_SERVICE_NAME': '0', 23:43:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-xwnjIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tumAXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-POOJ9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-_0Peru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-jqA9RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:43:02 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-EFLt4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-oaIfJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-J9LdwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-AdnxVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zm_LOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-dLBDtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-tpT0F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KlQIPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-D02od9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:03 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-0N2mBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:43:04 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-yeRC2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:43:04 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-SAejhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:43:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:43:04 INFO - Running setup.py install for manifestparser 23:43:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:43:04 INFO - Running setup.py install for mozcrash 23:43:04 INFO - Running setup.py install for mozdebug 23:43:04 INFO - Running setup.py install for mozdevice 23:43:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:43:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:43:04 INFO - Running setup.py install for mozfile 23:43:04 INFO - Running setup.py install for mozhttpd 23:43:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Running setup.py install for mozinfo 23:43:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Running setup.py install for mozInstall 23:43:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Running setup.py install for mozleak 23:43:05 INFO - Running setup.py install for mozlog 23:43:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Running setup.py install for moznetwork 23:43:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:43:05 INFO - Running setup.py install for mozprocess 23:43:06 INFO - Running setup.py install for mozprofile 23:43:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:43:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:43:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:43:06 INFO - Running setup.py install for mozrunner 23:43:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:43:06 INFO - Running setup.py install for mozscreenshot 23:43:06 INFO - Running setup.py install for moztest 23:43:06 INFO - Running setup.py install for mozversion 23:43:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:43:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:43:06 INFO - Cleaning up... 23:43:06 INFO - Return code: 0 23:43:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:43:06 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')]} 23:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:06 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')]} 23:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:06 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:43:06 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 23:43:06 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 23:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:43:06 INFO - 'HOME': '/Users/cltbld', 23:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:43:06 INFO - 'LOGNAME': 'cltbld', 23:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:06 INFO - 'MOZ_NO_REMOTE': '1', 23:43:06 INFO - 'NO_EM_RESTART': '1', 23:43:06 INFO - 'PAGER': '/bin/cat', 23:43:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:06 INFO - 'PWD': '/builds/slave/test', 23:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:43:06 INFO - 'SHELL': '/bin/bash', 23:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:43:06 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:43:06 INFO - 'USER': 'cltbld', 23:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:06 INFO - 'XPC_FLAGS': '0x0', 23:43:06 INFO - 'XPC_SERVICE_NAME': '0', 23:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-k_RPME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-IBVEJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:43:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-iBOwBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KIPAlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-h5QHPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-xqsqqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-L7rMKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-10uFbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:43:07 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)) 23:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:43:07 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-yn82QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ag6fnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-soZngk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9_QSDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Muvy1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-luHAEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-4DhryS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cWkcU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:43:08 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)) 23:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:43:08 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-HA39lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:43:08 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)) 23:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:43:08 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)) 23:43:08 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)) 23:43:08 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)) 23:43:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:43:11 INFO - Downloading blessings-1.6.tar.gz 23:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:43:11 INFO - Installing collected packages: blessings 23:43:11 INFO - Running setup.py install for blessings 23:43:11 INFO - Successfully installed blessings 23:43:11 INFO - Cleaning up... 23:43:11 INFO - Return code: 0 23:43:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:43:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:12 INFO - Reading from file tmpfile_stdout 23:43:12 INFO - Current package versions: 23:43:12 INFO - blessings == 1.6 23:43:12 INFO - blobuploader == 1.2.4 23:43:12 INFO - docopt == 0.6.1 23:43:12 INFO - functools32 == 3.2.3-2 23:43:12 INFO - jsonschema == 2.5.1 23:43:12 INFO - manifestparser == 1.1 23:43:12 INFO - mozInstall == 1.12 23:43:12 INFO - mozcrash == 0.17 23:43:12 INFO - mozdebug == 0.1 23:43:12 INFO - mozdevice == 0.48 23:43:12 INFO - mozfile == 1.2 23:43:12 INFO - mozhttpd == 0.7 23:43:12 INFO - mozinfo == 0.9 23:43:12 INFO - mozleak == 0.1 23:43:12 INFO - mozlog == 3.4 23:43:12 INFO - moznetwork == 0.27 23:43:12 INFO - mozprocess == 0.23 23:43:12 INFO - mozprofile == 0.28 23:43:12 INFO - mozrunner == 6.12 23:43:12 INFO - mozscreenshot == 0.1 23:43:12 INFO - mozsystemmonitor == 0.3 23:43:12 INFO - moztest == 0.7 23:43:12 INFO - mozversion == 1.4 23:43:12 INFO - psutil == 3.1.1 23:43:12 INFO - requests == 1.2.3 23:43:12 INFO - wsgiref == 0.1.2 23:43:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:12 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:43:12 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 23:43:12 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 23:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:43:12 INFO - 'HOME': '/Users/cltbld', 23:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:43:12 INFO - 'LOGNAME': 'cltbld', 23:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:12 INFO - 'MOZ_NO_REMOTE': '1', 23:43:12 INFO - 'NO_EM_RESTART': '1', 23:43:12 INFO - 'PAGER': '/bin/cat', 23:43:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:12 INFO - 'PWD': '/builds/slave/test', 23:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:43:12 INFO - 'SHELL': '/bin/bash', 23:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:43:12 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:43:12 INFO - 'USER': 'cltbld', 23:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:12 INFO - 'XPC_FLAGS': '0x0', 23:43:12 INFO - 'XPC_SERVICE_NAME': '0', 23:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 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)) 23:43:12 INFO - Cleaning up... 23:43:12 INFO - Return code: 0 23:43:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:12 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 0x10949a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca7ad69a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, '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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 23:43:12 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 23:43:12 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 23:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:43:12 INFO - 'HOME': '/Users/cltbld', 23:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:43:12 INFO - 'LOGNAME': 'cltbld', 23:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:12 INFO - 'MOZ_NO_REMOTE': '1', 23:43:12 INFO - 'NO_EM_RESTART': '1', 23:43:12 INFO - 'PAGER': '/bin/cat', 23:43:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:12 INFO - 'PWD': '/builds/slave/test', 23:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:43:12 INFO - 'SHELL': '/bin/bash', 23:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:43:12 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:43:12 INFO - 'USER': 'cltbld', 23:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:12 INFO - 'XPC_FLAGS': '0x0', 23:43:12 INFO - 'XPC_SERVICE_NAME': '0', 23:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:43:12 INFO - Cleaning up... 23:43:12 INFO - Return code: 0 23:43:12 INFO - Running post-action listener: _resource_record_post_action 23:43:12 INFO - Running post-action listener: _start_resource_monitoring 23:43:12 INFO - Starting resource monitoring. 23:43:12 INFO - [mozharness: 2016-10-19 06:43:12.655537Z] Finished create-virtualenv step (success) 23:43:12 INFO - [mozharness: 2016-10-19 06:43:12.656500Z] Running install step. 23:43:12 INFO - Running pre-action listener: _resource_record_pre_action 23:43:12 INFO - Running main action method: install 23:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:12 INFO - Reading from file tmpfile_stdout 23:43:12 INFO - Detecting whether we're running mozinstall >=1.0... 23:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:43:13 INFO - Reading from file tmpfile_stdout 23:43:13 INFO - Output received: 23:43:13 INFO - Usage: mozinstall [options] installer 23:43:13 INFO - Options: 23:43:13 INFO - -h, --help show this help message and exit 23:43:13 INFO - -d DEST, --destination=DEST 23:43:13 INFO - Directory to install application into. [default: 23:43:13 INFO - "/builds/slave/test"] 23:43:13 INFO - --app=APP Application being installed. [default: firefox] 23:43:13 INFO - mkdir: /builds/slave/test/build/application 23:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:43:47 INFO - Reading from file tmpfile_stdout 23:43:47 INFO - Output received: 23:43:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:43:47 INFO - Running post-action listener: _resource_record_post_action 23:43:47 INFO - [mozharness: 2016-10-19 06:43:47.154309Z] Finished install step (success) 23:43:47 INFO - [mozharness: 2016-10-19 06:43:47.154452Z] Running run-tests step. 23:43:47 INFO - Running pre-action listener: _resource_record_pre_action 23:43:47 INFO - Running main action method: run_tests 23:43:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:43:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:43:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:43:47 INFO - 2016-10-18 23:43:47.169 screenresolution[1658:9853] starting screenresolution argv=screenresolution get 23:43:47 INFO - 2016-10-18 23:43:47.171 screenresolution[1658:9853] Display 0: 1600x1200x32@60 23:43:47 INFO - 2016-10-18 23:43:47.178 screenresolution[1659:9855] starting screenresolution argv=screenresolution list 23:43:47 INFO - Available Modes on Display 0 23:43:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:43:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:43:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:43:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:43:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:43:47 INFO - Intel Iris: 23:43:47 INFO - Chipset Model: Intel Iris 23:43:47 INFO - Type: GPU 23:43:47 INFO - Bus: Built-In 23:43:47 INFO - VRAM (Dynamic, Max): 1536 MB 23:43:47 INFO - Vendor: Intel (0x8086) 23:43:47 INFO - Device ID: 0x0a2e 23:43:47 INFO - Revision ID: 0x0009 23:43:47 INFO - Displays: 23:43:47 INFO - Display: 23:43:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:43:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:43:47 INFO - Display Serial Number: 1600x1200 60 23:43:47 INFO - Main Display: Yes 23:43:47 INFO - Mirror: Off 23:43:47 INFO - Online: Yes 23:43:47 INFO - Rotation: Supported 23:43:47 INFO - Return code: 0 23:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:43:47 INFO - Python 2.7.3 23:43:47 INFO - Return code: 0 23:43:47 INFO - grabbing minidump binary from tooltool 23:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:47 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 0x1092aed30>, '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 0x1090d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:43:47 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 23:43:47 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 23:43:47 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 23:43:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:43:47 INFO - Return code: 0 23:43:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:43:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:43:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:43:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:43:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 23:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0308 --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 23:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nwjtZv8oz0/Render', 23:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:43:47 INFO - 'HOME': '/Users/cltbld', 23:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:43:47 INFO - 'LOGNAME': 'cltbld', 23:43:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:43:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:47 INFO - 'MOZ_NO_REMOTE': '1', 23:43:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:43:47 INFO - 'NO_EM_RESTART': '1', 23:43:47 INFO - 'PAGER': '/bin/cat', 23:43:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:47 INFO - 'PWD': '/builds/slave/test', 23:43:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:43:47 INFO - 'SHELL': '/bin/bash', 23:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CyZWIQQrsZ/Listeners', 23:43:47 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 23:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:43:47 INFO - 'USER': 'cltbld', 23:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:47 INFO - 'XPC_FLAGS': '0x0', 23:43:47 INFO - 'XPC_SERVICE_NAME': '0', 23:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:43:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 23:43:47 INFO - mozversion application_buildid: 20161018195923 23:43:47 INFO - mozversion application_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3 23:43:47 INFO - mozversion application_display_name: Nightly 23:43:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:43:47 INFO - mozversion application_name: Firefox 23:43:47 INFO - mozversion application_remotingname: firefox 23:43:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:43:47 INFO - mozversion application_vendor: Mozilla 23:43:47 INFO - mozversion application_version: 52.0a1 23:43:47 INFO - mozversion platform_buildid: 20161018195923 23:43:47 INFO - mozversion platform_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3 23:43:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:43:47 INFO - mozversion platform_version: 52.0a1 23:43:47 INFO - using testdate: 1476859427 23:43:47 INFO - actual date: 1476859427 23:43:47 INFO - starting webserver on 'localhost:49273' 23:43:47 INFO - SUITE-START | Running 2 tests 23:43:47 INFO - TEST-START | dromaeo_css 23:43:47 INFO - operating with platform_type : mac_ 23:43:47 INFO - Initialising browser for dromaeo_css test... 23:43:47 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuX5491/profile http://localhost:49273/getInfo.html) 23:43:50 INFO - PROCESS | 1669 | 2016-10-18 23:43:50.678 plugin-container[1671:9989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 23:43:50 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:43:50 INFO - PROCESS | 1669 | 2016-10-18 23:43:50.680 plugin-container[1671:9989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9417, name = 'com.apple.CFPasteboardClient' 23:43:50 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:43:50 INFO - PROCESS | 1669 | 2016-10-18 23:43:50.680 plugin-container[1671:9989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:43:50 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 23:43:50 INFO - PROCESS | 1669 | colorDepth:24 23:43:50 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 23:43:50 INFO - PROCESS | 1669 | __metrics 23:43:50 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 23:43:51 INFO - TEST-INFO | 1669: exit 0 23:43:51 INFO - Browser initialized. 23:43:51 INFO - Running cycle 1/1 for dromaeo_css test... 23:43:51 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuX5491/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:54 INFO - PROCESS | 1673 | 2016-10-18 23:43:54.009 plugin-container[1674:10113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 23:43:54 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:43:54 INFO - PROCESS | 1673 | 2016-10-18 23:43:54.009 plugin-container[1674:10113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 23:43:54 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:43:54 INFO - PROCESS | 1673 | 2016-10-18 23:43:54.009 plugin-container[1674:10113] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:44:03 INFO - PROCESS | 1673 | RSS: Main: 237682688 23:44:03 INFO - PROCESS | 1673 | 23:44:53 INFO - PROCESS | 1673 | 1476859493257 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] 23:47:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 23:47:08 INFO - PROCESS | 1673 | RSS: Main: 250994688 23:47:08 INFO - PROCESS | 1673 | 23:50:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 23:50:11 INFO - PROCESS | 1673 | RSS: Main: 263294976 23:50:11 INFO - PROCESS | 1673 | 23:53:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 23:53:13 INFO - PROCESS | 1673 | RSS: Main: 267943936 23:53:13 INFO - PROCESS | 1673 | 23:56:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 23:56:16 INFO - PROCESS | 1673 | RSS: Main: 267657216 23:56:16 INFO - PROCESS | 1673 | 23:59:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 23:59:19 INFO - PROCESS | 1673 | RSS: Main: 273100800 23:59:19 INFO - PROCESS | 1673 | 00:02:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 00:02:23 INFO - PROCESS | 1673 | RSS: Main: 273989632 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | __start_tp_report 00:02:23 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 00:02:23 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 00:02:23 INFO - PROCESS | 1673 | |i|pagename|runs| 00:02:23 INFO - PROCESS | 1673 | |0;dojo.html;3041.2396900774797;3075.2773098321904;3304.1739565108724;3305.7355411567073;3364.7812892162024;5536.114221724525;5568.777248910039;5570.303712035996;5590.552755779538;5642.605017648766;5516.889662206753;5598;5635;5683.687397193153;5696.658200507973;5065.924011139836;5137.897242055156;5154.38147422308;5208;5271.367435907678;5132.615053870974;5206.895862082756;5230.529252367286;5253.973730131344;5266.81566145185;5072;5114.514121158521;5121.4110377306715;5146.665466744668;5184.377874655028;5104.438511763703;5131.7434128293435;5154.175331946908;5158.458361872024;5217.895642087137;5080.77136528857;5080.847574572768;5088.160453525164;5126.2054381570915;5141.794328226866;5436.891262174774;5508.118701007819;5538.75075621594;5555.916661250085;5592.105263157916;6109.205803245594;6138.72561015194;6150.662375198357;6189.16446279751;6189.288231853349;5051.520105589963;6201.286852011986;6219.689015549205;6230.937690623081;6232.127502149703;5622.887542249132;5632.85917852057;5692.003899317603;5767.279090113736;5770.278715160605;6159.630422174684;6217.6891155442045;6240.31356550779;6240.906386404207;6245.219347581552;5984.401559843981;6037.698115094227;6104.8779024420155;6106.969465152734;6119.969400152971;5027.74861256943;5065.493450654905;5489.011977843947;5512.4211957744665;6049.818505444844;6071.848203794941;6103.428585615386;6114.144019837227;6125.204929265209;6171.722272497748;4472.753998530112;5153.5361817436615;6137.631742095488;6182.814515564451;6195.19462469876;4114.280000999813;4896.6572339935865;4905.681130726492;4918.5573298403315;4964.90070198594;3759.191773768598;4116.423700681907;4254.404383386328;4284.614384705392;4396.956030439718;4743.928841067318;5711.657300561979;5769.913451298234;5791.0734282514595;5804.071348584206;6209.627422354673;6211.596246243979;6242.250929888442;6260.5617606768;6280.183576135073;5611.158326251013;5646.548276137899;5718.142278658234;5727.513161381329;5739.971300143473;5403.0544654686055;5404.702741349263;5412.56699464042;5441.374241962146;5442.510174084351;6273.341299163613;6274.247090349188;6281.120643109877;6291.056341548621;6329.936700633027;6093.238345206849;6111.865232675556;6136.29432615255;6159.96920015415;6241.8751624966335;6145.340757995227;6251.9687401562705;6258.030005349178;6259.937400625936;6270;6095.116208149705;6100.23747031621;6117.602355846856;6123;6130.693465326626;5138.820141295037;6032.366601506778;6083.27000759894;6230.7819226326865;6232.626042437469;6016.187814644967;6029.51763858899;6068.998615228438;6081.969590152021;6139.4001448444;5954.702264886651;6005.789797357072;6055.576109672281;6104.38956104386;6113.17472141273;4537.8184872604725;5789.594728368974;5836.037053886062;5864.472197502245;5965.821025369246;5738.426157384228;6067.514598831995;6100.908486372619;6126.540509461719;6142.508599312134;5891.734871930859;5935.287765467999;5939.970300148471;5940.584159108994;5960.523158147251 00:02:23 INFO - PROCESS | 1673 | |1;ext.html;496.9031038947406;536.7975734355045;537.8225185688724;538.5422390967675;540.956723462123;39628;41409;41503.16993660125;41518.58481415185;41520.99999999993;43307.78346108273;43724.34413483792;43820.78089609547;43833.78083109588;43893.7805310973;9543.188828949531;9726;9738.415695058318;9742.610295588169;9761.463119528424;9663.420194788336;9734.951325243364;9744.171745401614;9744.951275243613;9748.415095094282;9710.70867873965;9736.367136136128;9752.12230899219;9762.121409073183;9763.707088787347;9668.95165524175;9736.269779766475;9741.464219467962;9745.756356091084;9757.170640495495;8079.95960020202;8840.690575829814;9042.23141033013;9550.665726699532;9558.996305387973;41584.58415415837;41671.791641041906;41878.79060604708;41909.790451047855;41910.32358705648;3374.6287908330064;3398.5581874356176;3413.6927676509235;3536.664016918389;3591.5510561179854;31442.21394465134;31655.84172079125;31947.840260798548;32543.51184732231;32688;3167.3870548970795;3238.498032804919;3242.854071566785;3245.7727959042877;3291.588551431071;423414.3609947274;423663;427613;430833;433613;61848.69075654683;61993.380066199665;62245.688771555855;62336.37663623306;62360.68819655872;64592.385190370616;65411.672941634984;65501.017484737764;65646.34353656498;65812.0384582695;16131.516054518383;16356.67286654284;16358.10030448313;16381;16430.424935127212;12493.18794278369;12595.370231488389;12626.053046021585;12657.240565566095;12677.619671409873;17223.741643875353;18120.909395452938;18169.091545422674;18180.63638727218;18189.18148683286;6648.086398248089;6711.664416779142;6792.796216113525;6857.965710171417;6874.518783685194;700.3206889317354;1028.4857571214393;1029.9378554405016;1055.5144633468537;1073.6944851146802;23765;24193.15372899532;30301.242468938453;30623.540646890313;31628.683713162573;1006.8288390973552;1044.681372181492;1068.4764465412047;1077.5258886090096;1169.7718944805852;15730.606734831492;16051.438199662956;16220;16341.754873676904;16343.101129437757;657772.7111364509;878945;882415;890944;900773;60016.39983600108;60081.399186007584;60552.69723651442;60703.69648151731;60706;1385.902986790935;1389.708161286121;1396.6648004478852;1416.5467050543932;1424.7221791750717;30786.230344241572;31404.52893206558;31889.681103188672;31925;32235;52582.47417525929;53044.734776327416;53133.88960269619;53165;53219.46780532145;479729;482616;482697;482773;483275;26570.601440978786;27354.726452736013;27418.217815841905;27468.939829513267;27803;47558;48223.75888120537;48305.5169448301;48644.513554863996;49289.50710492849;39783;40046.798596042165;40353.79823100866;40885.112832939536;41425;30822;39634.207315854095;39934.60065399309;40519.78440646785;41157.58842411658;31265.593048312425;38495;39406.60593394029;39592.812215633574;41442.792786035876;30787.07522785332;34215.627498001;34622.480662789574;34642.65357346394;34678.13304667303;22351.758994945965;26061.869690651427;26192.8690356547;26397.73602263953;26470.338241544116 00:02:23 INFO - PROCESS | 1673 | |2;jquery.html;353.24229527663164;379.6981399787169;382.7522277375754;386.55353067207386;405.9715819892607;15065.62335941602;15199.54401367958;18754.48457306865;20193.899030504846;20477.692834607475;16485.258163382645;19430.70853937192;20053.59892802143;20182.596348073028;20187.495312617193;4714.4578373486975;5219.478052194779;5860.648361098348;5929.110633404981;6101.60339577926;5728.942710572883;5897.056470964647;5952.285725712899;5968.5523585731025;5968.582199246054;5908.438698323653;6163.599366041193;6530.902036469457;6546.378094081055;6617.742628900518;5519.641223320491;5861;6351.968240158816;6615.073889655428;6617.073609694623;5721.628094173907;5735.4551317624755;5740.7703691852275;5744.310682718059;5757.193992840964;12679.619611411581;13966.720665586732;24075.79621018942;24523.141690040942;25093;1755.6664233795539;2074.2429013410147;2108.862923909941;2109.0193060226916;2114.7145135406677;9642.132208101304;11959;12801.871981280254;15194.088354698642;15456.690866182726;1767.2135899524717;1803.8917664940145;1820.2082094289026;1853.1753369750465;1893.3562588719763;114410.42794786056;121188.78811211951;121320;121351;122030;9253.72238832836;11014.229003969805;11507.482163302671;11508.309501429774;11662.300261984308;14154;14270.714585708227;14277.928610356881;14282.57152285433;14282.642933926734;7951.721689740947;8245.340372770164;8261.752147435587;8347.332213422911;8416.410851240355;6223.626582405071;6716.932830671631;7244.855102897913;8043;8120.390970677223;4450.465944086665;4460.799264033127;4465.821367145278;4468.575485328888;4498.2577874650315;1758.4109323376556;1906.3708976037874;1937.118611031977;1941.0876887862964;1954.1792447172222;774.9535027898344;816.6814942172557;896.8161526886972;917.6467060181744;972.4586530349334;17362.826371736122;20113.698294525595;20994.79005209959;21285.82927938978;21649.252949016914;881.678187461572;892.8482556114121;902.9819403612022;945.3666043750566;1007.3250921882357;10839.620613278497;11200.663980080612;11245.606403775828;11256;11280.717982050515;547864;553416;561178;566585;569584;121571.78428215605;123440;126987;131034.68965310416;138024.30987844997;940.8071345374182;975.6633961283204;989.8663680403198;989.9010098990187;990.5344488090586;15109.715674167819;16915.069671168203;17009.404670836462;19155.71266430977;19169.712454312918;52544.737276313375;52619.73690131525;53484.465155349506;53530.46469535411;54439.18341224964;68634;69926.25184370228;73204.26795732188;80702.19297806946;84173.15826841653;7608.581528016012;7904.446688731734;8082.232187942136;8106.554139522147;8255.917440825515;9146;11860.525578977089;12408.62774116778;12976.286304253357;13000;10170.08469237772;11204.215704900578;11271.70375406806;11379.431028448378;11650.533978641099;9333.906660933575;10796.29824061465;11168.888311116785;11364.488598012944;11597.82603260935;11281.71795705114;11543.076553875837;11713.414329283672;11782;11863.228890122115;5980.372060933713;7152.070230869954;7219.278072192738;7479.102507699111;8334.749957501284;9220.308476864397;10936.553491097015;10974.890251097388;11134.554617815196;11408.315501069963 00:02:23 INFO - PROCESS | 1673 | |3;mootools.html;1551.852574005469;1573.6065983504125;1814.6915024445843;1880.5768702042033;1934.516370907273;7926.8810967835525;8240.011198656155;8463.703770368038;9017.774555636111;9026.86459703105;6803.761868334609;8265.752027439172;8454.957725211381;8770.91229087709;8793.2965362771;1766.9116544172805;2370.8340416170877;2376.6435034744754;2381.583222935988;2383.4041489627593;2348.765123487646;2372.5729368713623;2399.7120345558556;2404.146527982567;2404.4589967257316;2015.9092840822198;2379.3813608461837;2382.9634109162484;2391.820613453989;2402.771736685012;2353.094058787362;2373.9881300593474;2375.228050883461;2377.322463098009;2405.651180578815;2345.73024102227;2362.4407890791995;2364.9408764780846;2378.452955820154;2383.5232953409386;7126.786196414116;8789.077146899548;8854.601542930584;8888.644454221823;8894.555272236426;5203.557697595709;5237.188235823453;5362.678239305654;5364;5398.028354896117;3128.765342599314;3500.6324335944614;3579.2483578448555;3644.4351125575577;3663.7435379523445;1022.3099407899713;1051.5478344311944;1060.0671409159968;1080.3895798873612;1083.377058191535;9921.900780992242;11642;11734.354610496419;11770.352630605314;11774.646760597196;4555.33947577606;4705.294205869079;4800.303955926441;4821.4696383397795;4821.590164835958;9830.75423114428;9897.554610042565;9897.950510247403;9902.356346837432;9909.058639429242;5346.652467589594;5361.35663720356;5366.838994830161;5372.57019438444;5389.083855744533;4353.34699795027;4555.2256116460285;4839.128956787742;4913.901721965541;4933.45731969483;3867.284552357823;3894.6494815466176;3980.24375368679;3987.960120398759;3999.3800960851113;1058.7564860082225;1175.18324764288;1271.9442862424166;1316.605018494467;1339.7052648417332;853.6798700487317;907.5598334807613;923.7598224461689;924.653255029364;948.9857652135223;5692.6299790513485;5934.6142500737315;5951.3751056139345;6014.48876845473;6016.939830601726;408.82420559159254;432.3838530094665;432.6063282413052;436.09292671243736;447.85220877110476;3922.37242041272;3924.3132451820816;3996.2806694795217;4002.8799136025964;4042.8180731867137;10494.790104197873;10832.404217768099;10891.618793342195;10907.83638245427;10911.072558832588;9109.134632209929;10449.373037617766;10847.077998370069;10858.728531786768;10870.076043536388;7647.273509016634;8084.717034903751;8191.467554608931;8279.54462504568;8287.419880608299;5984.8803023940145;6013.609115407485;6070.817875463743;6079.483243924316;6212.347703491249;10110.342827716175;10135.239857010607;10199.796004080024;10200.846987295048;10210.97890210973;9505.904940950502;9856.310058296138;10022.3485473444;10068.748281293027;10323.845142322722;6915.273896241023;7122.002919591365;7317.3780228679025;7490.737824176128;7574.621268936421;7374.963125184339;7486.43851711113;8285.751427457186;8442;8455.450395724258;5599.412061733622;6216.160818289473;7003.6848341823725;7120.857582848211;7657.57883316423;5896.380880007709;7399.556026638419;7410;7427.591482468516;7449.515781474187;5969.582129250911;6967.1291088613925;6992.986017027604;7008.684609192496;7104.928950710427;4620.953790462052;4840;4899.1426500363;4977.129002424634;4991.575716064175;4617.868622187586;5009.448960614257;5058.974705126451;5118.616103792156;5138.97430512845 00:02:23 INFO - PROCESS | 1673 | |4;prototype.html;239.63216462729707;305.1455923414439;307.96612372639004;309.5650610891698;315.3031799722613;4443.1780120677695;4544.1593305238475;5384.488473595012;5471.507564319206;5505.311836020497;4530.954690453094;5309.522143007129;5437.6465529740535;5446.15584584389;5475.206095116216;3371.4774209997368;3939.9803000985025;4043.97978010111;4083.8361067095884;4119.40268661044;3240.967590324102;3537;3832.2527107214105;3920.0395903003805;4112.712110152291;3556;3951.170254246612;4036.3945408188715;4166.708330416872;4186.660268714013;4001.7198796084285;4125.50493940726;4143.617419983491;4171.998720307135;4176;3223.838808059611;3571.8214089295693;3575.570931488212;3576.5708115026105;3583.211693427442;4318.935215971763;5269.156934890438;5361.624686271963;5373.489518495737;5377.838664840061;4553;4736.5026672199265;4742.857714268543;4796.71219726814;4833.951660483385;4740.454847692525;5212.50481204285;5731.019869667791;5750.45370689788;5802.65184088956;3962.722609417342;3977.6817854571855;3977.9005524861823;3984.163325701606;3991.720579559432;5004.149294619887;5266.8939522700275;5269.683818970874;5290.576753859682;5403.5136837684795;3839.7504162229366;3998.8400463981116;4011.3982902564267;4015.4980627421573;4048.7570745755347;4099.344104943195;4108.26051310761;4112.691548133902;4115.42384066225;4144.295469770146;4743.501932297128;4753.215719406259;4770.976145119322;4778.856634300907;4780.880477988078;5500.339959204921;5754.539636829044;5793.594448388572;5798.797042103506;5885.205497257815;1367.746966811143;1394.7280280345235;1401.7056418152301;1405.936732847004;1407.324484247567;741.3320029932652;806.2944923192207;852.2415050604967;870.4516154822421;872.2280781508284;5143.408508021551;5226.451222621646;5272.789088436496;5340.25236466895;5380.435054319319;5929.584929055011;5976;5988.371221021817;5995.820125396245;6031.276246850318;4277.668670574544;4563.703359281636;5189.273501709764;5494.395616482184;5501.697406642593;3081.4284714795617;3113.626364836234;3122.4379611669665;3132.4024747379;3144.3901561703733;5722.971385143131;6142.86357023943;6189.35011823752;6203.937960620427;6283.622982621057;5330.854795670573;5818.941810581925;5892.6759028253855;6015;6106.328303886569;5524.447555244444;5887.381824908409;6007.897235967376;6148.4773794227995;6236.033414820619;5040.117979353525;5220.843374698765;5590.189422533628;5607.018771715015;5969.641821490724;4636.235021221461;5486.945130548644;5881.38245484218;5884.205632239764;5890.674598215367;5350.8127215547265;5406.83779486616;6157.230346206724;6192.225971753531;6227;3754.9061273468383;4008.699347548887;4084.5302790179508;4113.4858142732155;4120.21715873983;5502.734371094591;5719.456651618089;5779.739911704068;6010.519158467225;6035.909461358171;5622.746976385991;5830.591858569859;5847.210626565529;5853.531717462508;5873.825234952941;5754.769809207753;5762.193292938908;5776.508996735324;5780.421957804186;5813.6221145625395;4754.429468463806;5690.260266165372;5693.886122277614;5708.4862362387585;5730.3410107838135;5495.450454954633;5499.900019995936;5669.34802497718;5748.425157484218;5809.970950145223;4311.611954924072;4496.797644106088;5427.6472029317965;5618.325800903917;5659.717014149359 00:02:23 INFO - PROCESS | 1673 | |5;yui.html;634.5753782757708;653.9411452969229;729.901463302454;785.858545461817;818.3085292927478;11517;14925;15962.680746385066;15972.121533315663;15983.760243596354;12068;14627.561173164795;14651.413943442276;14678.63303417415;14863.702725945475;697.5082566790426;716.9426445884343;853.2875049333813;863.2964134230598;880.4541184465609;883.7348795361398;885.4819930340726;889.995550022249;897.8428774964387;898.4115404410089;942.185009966381;944.5371767833781;945.5036106044313;945.9830682016825;946.1106559833769;925.8148370325962;928.424376886328;929.1869614087673;936.6300311377001;938.2728385501223;613.2058983616183;684.0389253099403;697.4072038767058;698.322627051759;700.1073631120312;14502.202378869159;14505.129692218501;14512.564623061326;14522.491712790108;14571.417143314255;14085.929570352186;14243.581540430327;14268.072575282677;14286.071405358618;14368.71262574753;11762.470688818938;11766.764664706743;11833.822492662617;11835.704107397298;11907.821382679267;3722.4788529605876;3781.3949768037255;3790.8294126764085;3811.313963486571;3912.3544615138467;109592;111446.32830507548;114149.42925285404;114617.4269128649;116577.41711291474;11066.633391564761;11119.27724697892;11122.443877806078;11169.944150279196;11354.205205635692;22508.099676013102;22512.887435563043;22543.210987615355;22551.887240563694;22567.887160564092;7914;8016.238457346542;8065.314448271845;8256.215659512336;8262.132476090044;4303.63419109373;4419.18245124653;4518.977405112953;4640.350350950869;4664.906701865943;3562.4656301554455;3620.6922411594783;3677.3013127505687;3682.6317368262958;3682.797446140488;579.2151634535144;641.0480436551717;651.84681599824;660.8579155481499;673.1854456108066;594.6105301027834;625.744934445776;635.2821311917504;655.167936720372;660.5079215984026;9356.204722598519;9412.294077944181;9537.284703647254;9558.617655293849;9560.713178604654;570.1277046119361;635.5741653092439;668.9632070236087;678.3996163395332;690.5821977703417;3790.431435284674;4377.080813029236;4502.655361039471;4539.795709193094;4586.72479651222;119517.8048219524;133914.33042834722;134949;138395;162439.37560624242;82555;115358;117415;120953.13804602672;122193.9476960161;101694.98305016855;105814;106506.46746766217;107643;110661;9708.174805141642;9916.702498925044;9969.800603988024;9998.400095994264;10003.449810260505;39269.60730392774;51505.48494515157;55143.72428137834;59619;61955.07067394083;83875.5806220965;87243.56378218069;87991.56004219937;90946;93202.53398732963;10746.677599672023;10832.350058996486;12967.41646625923;13111.27887966171;13613.52352667652;14683.19242441676;14728.852711472748;14769.261536923326;15372.38510459569;15415.997960132556;13481;13563.704632028288;13792.103513271606;13869.791953120914;14146.504872329419;13519.46586327431;13661.795073073714;13704.588862334145;13753.793693094813;14012.369443374868;12980.675483112998;13482.52811151605;13614.931925340707;13676;13794.103383280055;10861;11312.717182070184;11339.149563782914;11381.829272561085;11393.601223957121;8859.424137431046;11000.559977600806;11565.479553420284;11581.594644187413;11615.361155136548 00:02:23 INFO - PROCESS | 1673 | __end_tp_report 00:02:23 INFO - PROCESS | 1673 | __start_cc_report 00:02:23 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,367 00:02:23 INFO - PROCESS | 1673 | __end_cc_report 00:02:23 INFO - PROCESS | 1673 | __startTimestamp1476860543089__endTimestamp 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | ------- Summary: start ------- 00:02:23 INFO - PROCESS | 1673 | Number of tests: 6 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5614.52 Median:5770.28 stddev:682.08 (11.8%) stddev-sans-first:653.62 00:02:23 INFO - PROCESS | 1673 | Values: 3041.2 3075.3 3304.2 3305.7 3364.8 5536.1 5568.8 5570.3 5590.6 5642.6 5516.9 5598.0 5635.0 5683.7 5696.7 5065.9 5137.9 5154.4 5208.0 5271.4 5132.6 5206.9 5230.5 5254.0 5266.8 5072.0 5114.5 5121.4 5146.7 5184.4 5104.4 5131.7 5154.2 5158.5 5217.9 5080.8 5080.8 5088.2 5126.2 5141.8 5436.9 5508.1 5538.8 5555.9 5592.1 6109.2 6138.7 6150.7 6189.2 6189.3 5051.5 6201.3 6219.7 6230.9 6232.1 5622.9 5632.9 5692.0 5767.3 5770.3 6159.6 6217.7 6240.3 6240.9 6245.2 5984.4 6037.7 6104.9 6107.0 6120.0 5027.7 5065.5 5489.0 5512.4 6049.8 6071.8 6103.4 6114.1 6125.2 6171.7 4472.8 5153.5 6137.6 6182.8 6195.2 4114.3 4896.7 4905.7 4918.6 4964.9 3759.2 4116.4 4254.4 4284.6 4397.0 4743.9 5711.7 5769.9 5791.1 5804.1 6209.6 6211.6 6242.3 6260.6 6280.2 5611.2 5646.5 5718.1 5727.5 5740.0 5403.1 5404.7 5412.6 5441.4 5442.5 6273.3 6274.2 6281.1 6291.1 6329.9 6093.2 6111.9 6136.3 6160.0 6241.9 6145.3 6252.0 6258.0 6259.9 6270.0 6095.1 6100.2 6117.6 6123.0 6130.7 5138.8 6032.4 6083.3 6230.8 6232.6 6016.2 6029.5 6069.0 6082.0 6139.4 5954.7 6005.8 6055.6 6104.4 6113.2 4537.8 5789.6 5836.0 5864.5 5965.8 5738.4 6067.5 6100.9 6126.5 6142.5 5891.7 5935.3 5940.0 5940.6 5960.5 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:72000.98 Median:41472.98 stddev:165499.66 (399.1%) stddev-sans-first:165876.86 00:02:23 INFO - PROCESS | 1673 | Values: 496.9 536.8 537.8 538.5 541.0 39628.0 41409.0 41503.2 41518.6 41521.0 43307.8 43724.3 43820.8 43833.8 43893.8 9543.2 9726.0 9738.4 9742.6 9761.5 9663.4 9735.0 9744.2 9745.0 9748.4 9710.7 9736.4 9752.1 9762.1 9763.7 9669.0 9736.3 9741.5 9745.8 9757.2 8080.0 8840.7 9042.2 9550.7 9559.0 41584.6 41671.8 41878.8 41909.8 41910.3 3374.6 3398.6 3413.7 3536.7 3591.6 31442.2 31655.8 31947.8 32543.5 32688.0 3167.4 3238.5 3242.9 3245.8 3291.6 423414.4 423663.0 427613.0 430833.0 433613.0 61848.7 61993.4 62245.7 62336.4 62360.7 64592.4 65411.7 65501.0 65646.3 65812.0 16131.5 16356.7 16358.1 16381.0 16430.4 12493.2 12595.4 12626.1 12657.2 12677.6 17223.7 18120.9 18169.1 18180.6 18189.2 6648.1 6711.7 6792.8 6858.0 6874.5 700.3 1028.5 1029.9 1055.5 1073.7 23765.0 24193.2 30301.2 30623.5 31628.7 1006.8 1044.7 1068.5 1077.5 1169.8 15730.6 16051.4 16220.0 16341.8 16343.1 657772.7 878945.0 882415.0 890944.0 900773.0 60016.4 60081.4 60552.7 60703.7 60706.0 1385.9 1389.7 1396.7 1416.5 1424.7 30786.2 31404.5 31889.7 31925.0 32235.0 52582.5 53044.7 53133.9 53165.0 53219.5 479729.0 482616.0 482697.0 482773.0 483275.0 26570.6 27354.7 27418.2 27468.9 27803.0 47558.0 48223.8 48305.5 48644.5 49289.5 39783.0 40046.8 40353.8 40885.1 41425.0 30822.0 39634.2 39934.6 40519.8 41157.6 31265.6 38495.0 39406.6 39592.8 41442.8 30787.1 34215.6 34622.5 34642.7 34678.1 22351.8 26061.9 26192.9 26397.7 26470.3 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:33644.82 Median:12723.12 stddev:93895.09 (738.0%) stddev-sans-first:94125.22 00:02:23 INFO - PROCESS | 1673 | Values: 353.2 379.7 382.8 386.6 406.0 15065.6 15199.5 18754.5 20193.9 20477.7 16485.3 19430.7 20053.6 20182.6 20187.5 4714.5 5219.5 5860.6 5929.1 6101.6 5728.9 5897.1 5952.3 5968.6 5968.6 5908.4 6163.6 6530.9 6546.4 6617.7 5519.6 5861.0 6352.0 6615.1 6617.1 5721.6 5735.5 5740.8 5744.3 5757.2 12679.6 13966.7 24075.8 24523.1 25093.0 1755.7 2074.2 2108.9 2109.0 2114.7 9642.1 11959.0 12801.9 15194.1 15456.7 1767.2 1803.9 1820.2 1853.2 1893.4 114410.4 121188.8 121320.0 121351.0 122030.0 9253.7 11014.2 11507.5 11508.3 11662.3 14154.0 14270.7 14277.9 14282.6 14282.6 7951.7 8245.3 8261.8 8347.3 8416.4 6223.6 6716.9 7244.9 8043.0 8120.4 4450.5 4460.8 4465.8 4468.6 4498.3 1758.4 1906.4 1937.1 1941.1 1954.2 775.0 816.7 896.8 917.6 972.5 17362.8 20113.7 20994.8 21285.8 21649.3 881.7 892.8 903.0 945.4 1007.3 10839.6 11200.7 11245.6 11256.0 11280.7 547864.0 553416.0 561178.0 566585.0 569584.0 121571.8 123440.0 126987.0 131034.7 138024.3 940.8 975.7 989.9 989.9 990.5 15109.7 16915.1 17009.4 19155.7 19169.7 52544.7 52619.7 53484.5 53530.5 54439.2 68634.0 69926.3 73204.3 80702.2 84173.2 7608.6 7904.4 8082.2 8106.6 8255.9 9146.0 11860.5 12408.6 12976.3 13000.0 10170.1 11204.2 11271.7 11379.4 11650.5 9333.9 10796.3 11168.9 11364.5 11597.8 11281.7 11543.1 11713.4 11782.0 11863.2 5980.4 7152.1 7219.3 7479.1 8334.7 9220.3 10936.6 10974.9 11134.6 11408.3 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5622.77 Median:4839.56 stddev:3165.71 (65.4%) stddev-sans-first:3159.81 00:02:23 INFO - PROCESS | 1673 | Values: 1551.9 1573.6 1814.7 1880.6 1934.5 7926.9 8240.0 8463.7 9017.8 9026.9 6803.8 8265.8 8455.0 8770.9 8793.3 1766.9 2370.8 2376.6 2381.6 2383.4 2348.8 2372.6 2399.7 2404.1 2404.5 2015.9 2379.4 2383.0 2391.8 2402.8 2353.1 2374.0 2375.2 2377.3 2405.7 2345.7 2362.4 2364.9 2378.5 2383.5 7126.8 8789.1 8854.6 8888.6 8894.6 5203.6 5237.2 5362.7 5364.0 5398.0 3128.8 3500.6 3579.2 3644.4 3663.7 1022.3 1051.5 1060.1 1080.4 1083.4 9921.9 11642.0 11734.4 11770.4 11774.6 4555.3 4705.3 4800.3 4821.5 4821.6 9830.8 9897.6 9898.0 9902.4 9909.1 5346.7 5361.4 5366.8 5372.6 5389.1 4353.3 4555.2 4839.1 4913.9 4933.5 3867.3 3894.6 3980.2 3988.0 3999.4 1058.8 1175.2 1271.9 1316.6 1339.7 853.7 907.6 923.8 924.7 949.0 5692.6 5934.6 5951.4 6014.5 6016.9 408.8 432.4 432.6 436.1 447.9 3922.4 3924.3 3996.3 4002.9 4042.8 10494.8 10832.4 10891.6 10907.8 10911.1 9109.1 10449.4 10847.1 10858.7 10870.1 7647.3 8084.7 8191.5 8279.5 8287.4 5984.9 6013.6 6070.8 6079.5 6212.3 10110.3 10135.2 10199.8 10200.8 10211.0 9505.9 9856.3 10022.3 10068.7 10323.8 6915.3 7122.0 7317.4 7490.7 7574.6 7375.0 7486.4 8285.8 8442.0 8455.5 5599.4 6216.2 7003.7 7120.9 7657.6 5896.4 7399.6 7410.0 7427.6 7449.5 5969.6 6967.1 6993.0 7008.7 7104.9 4621.0 4840.0 4899.1 4977.1 4991.6 4617.9 5009.4 5059.0 5118.6 5139.0 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4641.65 Median:5271.24 stddev:1431.98 (27.2%) stddev-sans-first:1397.36 00:02:23 INFO - PROCESS | 1673 | Values: 239.6 305.1 308.0 309.6 315.3 4443.2 4544.2 5384.5 5471.5 5505.3 4531.0 5309.5 5437.6 5446.2 5475.2 3371.5 3940.0 4044.0 4083.8 4119.4 3241.0 3537.0 3832.3 3920.0 4112.7 3556.0 3951.2 4036.4 4166.7 4186.7 4001.7 4125.5 4143.6 4172.0 4176.0 3223.8 3571.8 3575.6 3576.6 3583.2 4318.9 5269.2 5361.6 5373.5 5377.8 4553.0 4736.5 4742.9 4796.7 4834.0 4740.5 5212.5 5731.0 5750.5 5802.7 3962.7 3977.7 3977.9 3984.2 3991.7 5004.1 5266.9 5269.7 5290.6 5403.5 3839.8 3998.8 4011.4 4015.5 4048.8 4099.3 4108.3 4112.7 4115.4 4144.3 4743.5 4753.2 4771.0 4778.9 4780.9 5500.3 5754.5 5793.6 5798.8 5885.2 1367.7 1394.7 1401.7 1405.9 1407.3 741.3 806.3 852.2 870.5 872.2 5143.4 5226.5 5272.8 5340.3 5380.4 5929.6 5976.0 5988.4 5995.8 6031.3 4277.7 4563.7 5189.3 5494.4 5501.7 3081.4 3113.6 3122.4 3132.4 3144.4 5723.0 6142.9 6189.4 6203.9 6283.6 5330.9 5818.9 5892.7 6015.0 6106.3 5524.4 5887.4 6007.9 6148.5 6236.0 5040.1 5220.8 5590.2 5607.0 5969.6 4636.2 5486.9 5881.4 5884.2 5890.7 5350.8 5406.8 6157.2 6192.2 6227.0 3754.9 4008.7 4084.5 4113.5 4120.2 5502.7 5719.5 5779.7 6010.5 6035.9 5622.7 5830.6 5847.2 5853.5 5873.8 5754.8 5762.2 5776.5 5780.4 5813.6 4754.4 5690.3 5693.9 5708.5 5730.3 5495.5 5499.9 5669.3 5748.4 5810.0 4311.6 4496.8 5427.6 5618.3 5659.7 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:23776.10 Median:3649.00 stddev:37235.67 (1020.4%) stddev-sans-first:37299.58 00:02:23 INFO - PROCESS | 1673 | Values: 634.6 653.9 729.9 785.9 818.3 11517.0 14925.0 15962.7 15972.1 15983.8 12068.0 14627.6 14651.4 14678.6 14863.7 697.5 716.9 853.3 863.3 880.5 883.7 885.5 890.0 897.8 898.4 942.2 944.5 945.5 946.0 946.1 925.8 928.4 929.2 936.6 938.3 613.2 684.0 697.4 698.3 700.1 14502.2 14505.1 14512.6 14522.5 14571.4 14085.9 14243.6 14268.1 14286.1 14368.7 11762.5 11766.8 11833.8 11835.7 11907.8 3722.5 3781.4 3790.8 3811.3 3912.4 109592.0 111446.3 114149.4 114617.4 116577.4 11066.6 11119.3 11122.4 11169.9 11354.2 22508.1 22512.9 22543.2 22551.9 22567.9 7914.0 8016.2 8065.3 8256.2 8262.1 4303.6 4419.2 4519.0 4640.4 4664.9 3562.5 3620.7 3677.3 3682.6 3682.8 579.2 641.0 651.8 660.9 673.2 594.6 625.7 635.3 655.2 660.5 9356.2 9412.3 9537.3 9558.6 9560.7 570.1 635.6 669.0 678.4 690.6 3790.4 4377.1 4502.7 4539.8 4586.7 119517.8 133914.3 134949.0 138395.0 162439.4 82555.0 115358.0 117415.0 120953.1 122193.9 101695.0 105814.0 106506.5 107643.0 110661.0 9708.2 9916.7 9969.8 9998.4 10003.4 39269.6 51505.5 55143.7 59619.0 61955.1 83875.6 87243.6 87991.6 90946.0 93202.5 10746.7 10832.4 12967.4 13111.3 13613.5 14683.2 14728.9 14769.3 15372.4 15416.0 13481.0 13563.7 13792.1 13869.8 14146.5 13519.5 13661.8 13704.6 13753.8 14012.4 12980.7 13482.5 13614.9 13676.0 13794.1 10861.0 11312.7 11339.1 11381.8 11393.6 8859.4 11000.6 11565.5 11581.6 11615.4 00:02:23 INFO - PROCESS | 1673 | -------- Summary: end -------- 00:02:23 INFO - PROCESS | 1673 | 00:02:23 INFO - TEST-INFO | 1673: exit 0 00:02:23 INFO - TEST-OK | dromaeo_css | took 1116069ms 00:02:23 INFO - TEST-START | kraken 00:02:23 INFO - operating with platform_type : mac_ 00:02:23 INFO - Initialising browser for kraken test... 00:02:23 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp51ALYv/profile http://localhost:49273/getInfo.html) 00:02:26 INFO - PROCESS | 1682 | 2016-10-19 00:02:26.682 plugin-container[1683:12363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 00:02:26 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:02:26 INFO - PROCESS | 1682 | 2016-10-19 00:02:26.685 plugin-container[1683:12363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 00:02:26 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:02:26 INFO - PROCESS | 1682 | 2016-10-19 00:02:26.685 plugin-container[1683:12363] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:02:26 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 00:02:26 INFO - PROCESS | 1682 | colorDepth:24 00:02:26 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 00:02:26 INFO - PROCESS | 1682 | __metrics 00:02:26 ERROR - PROCESS | 1682 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 00:02:27 INFO - TEST-INFO | 1682: exit 0 00:02:27 INFO - Browser initialized. 00:02:27 INFO - Running cycle 1/1 for kraken test... 00:02:27 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp51ALYv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:02:29 INFO - PROCESS | 1684 | 2016-10-19 00:02:29.884 plugin-container[1685:12478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 00:02:29 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:02:29 INFO - PROCESS | 1684 | 2016-10-19 00:02:29.885 plugin-container[1685:12478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a13, name = 'com.apple.CFPasteboardClient' 00:02:29 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:02:29 INFO - PROCESS | 1684 | 2016-10-19 00:02:29.885 plugin-container[1685:12478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:02:39 INFO - PROCESS | 1684 | RSS: Main: 237256704 00:02:39 INFO - PROCESS | 1684 | 00:02:42 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 00:02:43 INFO - PROCESS | 1684 | RSS: Main: 249495552 00:02:43 INFO - PROCESS | 1684 | 00:02:46 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 00:02:46 INFO - PROCESS | 1684 | RSS: Main: 257081344 00:02:46 INFO - PROCESS | 1684 | 00:02:50 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 00:02:50 INFO - PROCESS | 1684 | RSS: Main: 259952640 00:02:50 INFO - PROCESS | 1684 | 00:02:52 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 00:02:53 INFO - PROCESS | 1684 | RSS: Main: 262201344 00:02:53 INFO - PROCESS | 1684 | 00:02:55 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:02:56 INFO - PROCESS | 1684 | RSS: Main: 262873088 00:02:56 INFO - PROCESS | 1684 | 00:02:58 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 00:02:59 INFO - PROCESS | 1684 | RSS: Main: 263409664 00:02:59 INFO - PROCESS | 1684 | 00:03:02 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 00:03:03 INFO - PROCESS | 1684 | RSS: Main: 263688192 00:03:03 INFO - PROCESS | 1684 | 00:03:05 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 00:03:06 INFO - PROCESS | 1684 | RSS: Main: 263917568 00:03:06 INFO - PROCESS | 1684 | 00:03:07 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:03:08 INFO - PROCESS | 1684 | RSS: Main: 263049216 00:03:08 INFO - PROCESS | 1684 | 00:03:10 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:03:11 INFO - PROCESS | 1684 | RSS: Main: 263180288 00:03:11 INFO - PROCESS | 1684 | 00:03:13 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:03:14 INFO - PROCESS | 1684 | RSS: Main: 263172096 00:03:14 INFO - PROCESS | 1684 | 00:03:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:03:18 INFO - PROCESS | 1684 | RSS: Main: 263516160 00:03:18 INFO - PROCESS | 1684 | 00:03:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:03:21 INFO - PROCESS | 1684 | RSS: Main: 263581696 00:03:21 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 00:03:23 INFO - PROCESS | 1684 | RSS: Main: 264921088 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | __start_tp_report 00:03:23 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 00:03:23 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 00:03:23 INFO - PROCESS | 1684 | |i|pagename|runs| 00:03:23 INFO - PROCESS | 1684 | |0;ai-astar;107;116;117;113;114;117;118;117;117;127 00:03:23 INFO - PROCESS | 1684 | |1;audio-beat-detection;170;147;109;190;150;146;121;117;144;115 00:03:23 INFO - PROCESS | 1684 | |2;audio-dft;196;238;270;197;196;195;195;192;195;194 00:03:23 INFO - PROCESS | 1684 | |3;audio-fft;79;59;69;88;71;86;71;60;80;58 00:03:23 INFO - PROCESS | 1684 | |4;audio-oscillator;81;72;86;70;70;71;125;99;101;85 00:03:23 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;90;88;90;87;89;86;91;96;91;87 00:03:23 INFO - PROCESS | 1684 | |6;imaging-darkroom;97;150;97;97;97;118;124;97;98;98 00:03:23 INFO - PROCESS | 1684 | |7;imaging-desaturate;90;91;86;87;92;88;92;90;88;90 00:03:23 INFO - PROCESS | 1684 | |8;json-parse-financial;58;59;58;57;56;62;70;72;54;59 00:03:23 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;70;103;106;122;112;69;70;69;72;71 00:03:23 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;84;91;89;91;75;101;103;110;114;109 00:03:23 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;150;147;166;161;141;162;161;161;198;158 00:03:23 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;248;182;181;176;172;180;209;242;172;191 00:03:23 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;70;69;72;73;61;72;90;91;85;68 00:03:23 INFO - PROCESS | 1684 | __end_tp_report 00:03:23 INFO - PROCESS | 1684 | __start_cc_report 00:03:23 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,945 00:03:23 INFO - PROCESS | 1684 | __end_cc_report 00:03:23 INFO - PROCESS | 1684 | __startTimestamp1476860603890__endTimestamp 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | ------- Summary: start ------- 00:03:23 INFO - PROCESS | 1684 | Number of tests: 14 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:4.97 (4.2%) stddev-sans-first:3.97 00:03:23 INFO - PROCESS | 1684 | Values: 107.0 116.0 117.0 113.0 114.0 117.0 118.0 117.0 117.0 127.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:146.50 stddev:25.97 (17.7%) stddev-sans-first:25.32 00:03:23 INFO - PROCESS | 1684 | Values: 170.0 147.0 109.0 190.0 150.0 146.0 121.0 117.0 144.0 115.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:206.80 Median:196.00 stddev:26.03 (13.3%) stddev-sans-first:27.31 00:03:23 INFO - PROCESS | 1684 | Values: 196.0 238.0 270.0 197.0 196.0 195.0 195.0 192.0 195.0 194.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:72.10 Median:75.00 stddev:10.98 (14.6%) stddev-sans-first:11.36 00:03:23 INFO - PROCESS | 1684 | Values: 79.0 59.0 69.0 88.0 71.0 86.0 71.0 60.0 80.0 58.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:76.50 stddev:17.87 (23.4%) stddev-sans-first:18.86 00:03:23 INFO - PROCESS | 1684 | Values: 81.0 72.0 86.0 70.0 70.0 71.0 125.0 99.0 101.0 85.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:2.88 (3.2%) stddev-sans-first:3.05 00:03:23 INFO - PROCESS | 1684 | Values: 90.0 88.0 90.0 87.0 89.0 86.0 91.0 96.0 91.0 87.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:97.00 stddev:18.01 (18.6%) stddev-sans-first:18.72 00:03:23 INFO - PROCESS | 1684 | Values: 97.0 150.0 97.0 97.0 97.0 118.0 124.0 97.0 98.0 98.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:90.00 stddev:2.07 (2.3%) stddev-sans-first:2.18 00:03:23 INFO - PROCESS | 1684 | Values: 90.0 91.0 86.0 87.0 92.0 88.0 92.0 90.0 88.0 90.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:59.00 stddev:5.93 (10.1%) stddev-sans-first:6.22 00:03:23 INFO - PROCESS | 1684 | Values: 58.0 59.0 58.0 57.0 56.0 62.0 70.0 72.0 54.0 59.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:86.40 Median:69.50 stddev:21.53 (31.0%) stddev-sans-first:22.00 00:03:23 INFO - PROCESS | 1684 | Values: 70.0 103.0 106.0 122.0 112.0 69.0 70.0 69.0 72.0 71.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:96.70 Median:79.50 stddev:12.66 (15.9%) stddev-sans-first:12.56 00:03:23 INFO - PROCESS | 1684 | Values: 84.0 91.0 89.0 91.0 75.0 101.0 103.0 110.0 114.0 109.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:161.00 stddev:15.34 (9.5%) stddev-sans-first:15.80 00:03:23 INFO - PROCESS | 1684 | Values: 150.0 147.0 166.0 161.0 141.0 162.0 161.0 161.0 198.0 158.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.30 Median:186.50 stddev:28.36 (15.2%) stddev-sans-first:22.78 00:03:23 INFO - PROCESS | 1684 | Values: 248.0 182.0 181.0 176.0 172.0 180.0 209.0 242.0 172.0 191.0 00:03:23 INFO - PROCESS | 1684 | 00:03:23 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:72.50 stddev:10.05 (13.9%) stddev-sans-first:10.49 00:03:23 INFO - PROCESS | 1684 | Values: 70.0 69.0 72.0 73.0 61.0 72.0 90.0 91.0 85.0 68.0 00:03:23 INFO - PROCESS | 1684 | -------- Summary: end -------- 00:03:23 INFO - PROCESS | 1684 | 00:03:24 INFO - TEST-INFO | 1684: exit 0 00:03:24 INFO - TEST-OK | kraken | took 61005ms 00:03:24 INFO - SUITE-END | took 1177s 00:03:25 INFO - Completed test suite (00:19:37) 00:03:25 INFO - javascript benchmark 00:03:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7302.85343624737, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3041.2396900774797, 3075.2773098321904, 3304.1739565108724, 3305.7355411567073, 3364.7812892162024, 5536.114221724525, 5568.777248910039, 5570.303712035996, 5590.552755779538, 5642.605017648766, 5516.889662206753, 5598.0, 5635.0, 5683.687397193153, 5696.658200507973, 5065.924011139836, 5137.897242055156, 5154.38147422308, 5208.0, 5271.367435907678, 5132.615053870974, 5206.895862082756, 5230.529252367286, 5253.973730131344, 5266.81566145185, 5072.0, 5114.514121158521, 5121.4110377306715, 5146.665466744668, 5184.377874655028, 5104.438511763703, 5131.7434128293435, 5154.175331946908, 5158.458361872024, 5217.895642087137, 5080.77136528857, 5080.847574572768, 5088.160453525164, 5126.2054381570915, 5141.794328226866, 5436.891262174774, 5508.118701007819, 5538.75075621594, 5555.916661250085, 5592.105263157916, 6109.205803245594, 6138.72561015194, 6150.662375198357, 6189.16446279751, 6189.288231853349, 5051.520105589963, 6201.286852011986, 6219.689015549205, 6230.937690623081, 6232.127502149703, 5622.887542249132, 5632.85917852057, 5692.003899317603, 5767.279090113736, 5770.278715160605, 6159.630422174684, 6217.6891155442045, 6240.31356550779, 6240.906386404207, 6245.219347581552, 5984.401559843981, 6037.698115094227, 6104.8779024420155, 6106.969465152734, 6119.969400152971, 5027.74861256943, 5065.493450654905, 5489.011977843947, 5512.4211957744665, 6049.818505444844, 6071.848203794941, 6103.428585615386, 6114.144019837227, 6125.204929265209, 6171.722272497748, 4472.753998530112, 5153.5361817436615, 6137.631742095488, 6182.814515564451, 6195.19462469876, 4114.280000999813, 4896.6572339935865, 4905.681130726492, 4918.5573298403315, 4964.90070198594, 3759.191773768598, 4116.423700681907, 4254.404383386328, 4284.614384705392, 4396.956030439718, 4743.928841067318, 5711.657300561979, 5769.913451298234, 5791.0734282514595, 5804.071348584206, 6209.627422354673, 6211.596246243979, 6242.250929888442, 6260.5617606768, 6280.183576135073, 5611.158326251013, 5646.548276137899, 5718.142278658234, 5727.513161381329, 5739.971300143473, 5403.0544654686055, 5404.702741349263, 5412.56699464042, 5441.374241962146, 5442.510174084351, 6273.341299163613, 6274.247090349188, 6281.120643109877, 6291.056341548621, 6329.936700633027, 6093.238345206849, 6111.865232675556, 6136.29432615255, 6159.96920015415, 6241.8751624966335, 6145.340757995227, 6251.9687401562705, 6258.030005349178, 6259.937400625936, 6270.0, 6095.116208149705, 6100.23747031621, 6117.602355846856, 6123.0, 6130.693465326626, 5138.820141295037, 6032.366601506778, 6083.27000759894, 6230.7819226326865, 6232.626042437469, 6016.187814644967, 6029.51763858899, 6068.998615228438, 6081.969590152021, 6139.4001448444, 5954.702264886651, 6005.789797357072, 6055.576109672281, 6104.38956104386, 6113.17472141273, 4537.8184872604725, 5789.594728368974, 5836.037053886062, 5864.472197502245, 5965.821025369246, 5738.426157384228, 6067.514598831995, 6100.908486372619, 6126.540509461719, 6142.508599312134, 5891.734871930859, 5935.287765467999, 5939.970300148471, 5940.584159108994, 5960.523158147251], "value": 5570.540257388769, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.9031038947406, 536.7975734355045, 537.8225185688724, 538.5422390967675, 540.956723462123, 39628.0, 41409.0, 41503.16993660125, 41518.58481415185, 41520.99999999993, 43307.78346108273, 43724.34413483792, 43820.78089609547, 43833.78083109588, 43893.7805310973, 9543.188828949531, 9726.0, 9738.415695058318, 9742.610295588169, 9761.463119528424, 9663.420194788336, 9734.951325243364, 9744.171745401614, 9744.951275243613, 9748.415095094282, 9710.70867873965, 9736.367136136128, 9752.12230899219, 9762.121409073183, 9763.707088787347, 9668.95165524175, 9736.269779766475, 9741.464219467962, 9745.756356091084, 9757.170640495495, 8079.95960020202, 8840.690575829814, 9042.23141033013, 9550.665726699532, 9558.996305387973, 41584.58415415837, 41671.791641041906, 41878.79060604708, 41909.790451047855, 41910.32358705648, 3374.6287908330064, 3398.5581874356176, 3413.6927676509235, 3536.664016918389, 3591.5510561179854, 31442.21394465134, 31655.84172079125, 31947.840260798548, 32543.51184732231, 32688.0, 3167.3870548970795, 3238.498032804919, 3242.854071566785, 3245.7727959042877, 3291.588551431071, 423414.3609947274, 423663.0, 427613.0, 430833.0, 433613.0, 61848.69075654683, 61993.380066199665, 62245.688771555855, 62336.37663623306, 62360.68819655872, 64592.385190370616, 65411.672941634984, 65501.017484737764, 65646.34353656498, 65812.0384582695, 16131.516054518383, 16356.67286654284, 16358.10030448313, 16381.0, 16430.424935127212, 12493.18794278369, 12595.370231488389, 12626.053046021585, 12657.240565566095, 12677.619671409873, 17223.741643875353, 18120.909395452938, 18169.091545422674, 18180.63638727218, 18189.18148683286, 6648.086398248089, 6711.664416779142, 6792.796216113525, 6857.965710171417, 6874.518783685194, 700.3206889317354, 1028.4857571214393, 1029.9378554405016, 1055.5144633468537, 1073.6944851146802, 23765.0, 24193.15372899532, 30301.242468938453, 30623.540646890313, 31628.683713162573, 1006.8288390973552, 1044.681372181492, 1068.4764465412047, 1077.5258886090096, 1169.7718944805852, 15730.606734831492, 16051.438199662956, 16220.0, 16341.754873676904, 16343.101129437757, 657772.7111364509, 878945.0, 882415.0, 890944.0, 900773.0, 60016.39983600108, 60081.399186007584, 60552.69723651442, 60703.69648151731, 60706.0, 1385.902986790935, 1389.708161286121, 1396.6648004478852, 1416.5467050543932, 1424.7221791750717, 30786.230344241572, 31404.52893206558, 31889.681103188672, 31925.0, 32235.0, 52582.47417525929, 53044.734776327416, 53133.88960269619, 53165.0, 53219.46780532145, 479729.0, 482616.0, 482697.0, 482773.0, 483275.0, 26570.601440978786, 27354.726452736013, 27418.217815841905, 27468.939829513267, 27803.0, 47558.0, 48223.75888120537, 48305.5169448301, 48644.513554863996, 49289.50710492849, 39783.0, 40046.798596042165, 40353.79823100866, 40885.112832939536, 41425.0, 30822.0, 39634.207315854095, 39934.60065399309, 40519.78440646785, 41157.58842411658, 31265.593048312425, 38495.0, 39406.60593394029, 39592.812215633574, 41442.792786035876, 30787.07522785332, 34215.627498001, 34622.480662789574, 34642.65357346394, 34678.13304667303, 22351.758994945965, 26061.869690651427, 26192.8690356547, 26397.73602263953, 26470.338241544116], "value": 20173.554934675012, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.24229527663164, 379.6981399787169, 382.7522277375754, 386.55353067207386, 405.9715819892607, 15065.62335941602, 15199.54401367958, 18754.48457306865, 20193.899030504846, 20477.692834607475, 16485.258163382645, 19430.70853937192, 20053.59892802143, 20182.596348073028, 20187.495312617193, 4714.4578373486975, 5219.478052194779, 5860.648361098348, 5929.110633404981, 6101.60339577926, 5728.942710572883, 5897.056470964647, 5952.285725712899, 5968.5523585731025, 5968.582199246054, 5908.438698323653, 6163.599366041193, 6530.902036469457, 6546.378094081055, 6617.742628900518, 5519.641223320491, 5861.0, 6351.968240158816, 6615.073889655428, 6617.073609694623, 5721.628094173907, 5735.4551317624755, 5740.7703691852275, 5744.310682718059, 5757.193992840964, 12679.619611411581, 13966.720665586732, 24075.79621018942, 24523.141690040942, 25093.0, 1755.6664233795539, 2074.2429013410147, 2108.862923909941, 2109.0193060226916, 2114.7145135406677, 9642.132208101304, 11959.0, 12801.871981280254, 15194.088354698642, 15456.690866182726, 1767.2135899524717, 1803.8917664940145, 1820.2082094289026, 1853.1753369750465, 1893.3562588719763, 114410.42794786056, 121188.78811211951, 121320.0, 121351.0, 122030.0, 9253.72238832836, 11014.229003969805, 11507.482163302671, 11508.309501429774, 11662.300261984308, 14154.0, 14270.714585708227, 14277.928610356881, 14282.57152285433, 14282.642933926734, 7951.721689740947, 8245.340372770164, 8261.752147435587, 8347.332213422911, 8416.410851240355, 6223.626582405071, 6716.932830671631, 7244.855102897913, 8043.0, 8120.390970677223, 4450.465944086665, 4460.799264033127, 4465.821367145278, 4468.575485328888, 4498.2577874650315, 1758.4109323376556, 1906.3708976037874, 1937.118611031977, 1941.0876887862964, 1954.1792447172222, 774.9535027898344, 816.6814942172557, 896.8161526886972, 917.6467060181744, 972.4586530349334, 17362.826371736122, 20113.698294525595, 20994.79005209959, 21285.82927938978, 21649.252949016914, 881.678187461572, 892.8482556114121, 902.9819403612022, 945.3666043750566, 1007.3250921882357, 10839.620613278497, 11200.663980080612, 11245.606403775828, 11256.0, 11280.717982050515, 547864.0, 553416.0, 561178.0, 566585.0, 569584.0, 121571.78428215605, 123440.0, 126987.0, 131034.68965310416, 138024.30987844997, 940.8071345374182, 975.6633961283204, 989.8663680403198, 989.9010098990187, 990.5344488090586, 15109.715674167819, 16915.069671168203, 17009.404670836462, 19155.71266430977, 19169.712454312918, 52544.737276313375, 52619.73690131525, 53484.465155349506, 53530.46469535411, 54439.18341224964, 68634.0, 69926.25184370228, 73204.26795732188, 80702.19297806946, 84173.15826841653, 7608.581528016012, 7904.446688731734, 8082.232187942136, 8106.554139522147, 8255.917440825515, 9146.0, 11860.525578977089, 12408.62774116778, 12976.286304253357, 13000.0, 10170.08469237772, 11204.215704900578, 11271.70375406806, 11379.431028448378, 11650.533978641099, 9333.906660933575, 10796.29824061465, 11168.888311116785, 11364.488598012944, 11597.82603260935, 11281.71795705114, 11543.076553875837, 11713.414329283672, 11782.0, 11863.228890122115, 5980.372060933713, 7152.070230869954, 7219.278072192738, 7479.102507699111, 8334.749957501284, 9220.308476864397, 10936.553491097015, 10974.890251097388, 11134.554617815196, 11408.315501069963], "value": 9339.999865728136, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.852574005469, 1573.6065983504125, 1814.6915024445843, 1880.5768702042033, 1934.516370907273, 7926.8810967835525, 8240.011198656155, 8463.703770368038, 9017.774555636111, 9026.86459703105, 6803.761868334609, 8265.752027439172, 8454.957725211381, 8770.91229087709, 8793.2965362771, 1766.9116544172805, 2370.8340416170877, 2376.6435034744754, 2381.583222935988, 2383.4041489627593, 2348.765123487646, 2372.5729368713623, 2399.7120345558556, 2404.146527982567, 2404.4589967257316, 2015.9092840822198, 2379.3813608461837, 2382.9634109162484, 2391.820613453989, 2402.771736685012, 2353.094058787362, 2373.9881300593474, 2375.228050883461, 2377.322463098009, 2405.651180578815, 2345.73024102227, 2362.4407890791995, 2364.9408764780846, 2378.452955820154, 2383.5232953409386, 7126.786196414116, 8789.077146899548, 8854.601542930584, 8888.644454221823, 8894.555272236426, 5203.557697595709, 5237.188235823453, 5362.678239305654, 5364.0, 5398.028354896117, 3128.765342599314, 3500.6324335944614, 3579.2483578448555, 3644.4351125575577, 3663.7435379523445, 1022.3099407899713, 1051.5478344311944, 1060.0671409159968, 1080.3895798873612, 1083.377058191535, 9921.900780992242, 11642.0, 11734.354610496419, 11770.352630605314, 11774.646760597196, 4555.33947577606, 4705.294205869079, 4800.303955926441, 4821.4696383397795, 4821.590164835958, 9830.75423114428, 9897.554610042565, 9897.950510247403, 9902.356346837432, 9909.058639429242, 5346.652467589594, 5361.35663720356, 5366.838994830161, 5372.57019438444, 5389.083855744533, 4353.34699795027, 4555.2256116460285, 4839.128956787742, 4913.901721965541, 4933.45731969483, 3867.284552357823, 3894.6494815466176, 3980.24375368679, 3987.960120398759, 3999.3800960851113, 1058.7564860082225, 1175.18324764288, 1271.9442862424166, 1316.605018494467, 1339.7052648417332, 853.6798700487317, 907.5598334807613, 923.7598224461689, 924.653255029364, 948.9857652135223, 5692.6299790513485, 5934.6142500737315, 5951.3751056139345, 6014.48876845473, 6016.939830601726, 408.82420559159254, 432.3838530094665, 432.6063282413052, 436.09292671243736, 447.85220877110476, 3922.37242041272, 3924.3132451820816, 3996.2806694795217, 4002.8799136025964, 4042.8180731867137, 10494.790104197873, 10832.404217768099, 10891.618793342195, 10907.83638245427, 10911.072558832588, 9109.134632209929, 10449.373037617766, 10847.077998370069, 10858.728531786768, 10870.076043536388, 7647.273509016634, 8084.717034903751, 8191.467554608931, 8279.54462504568, 8287.419880608299, 5984.8803023940145, 6013.609115407485, 6070.817875463743, 6079.483243924316, 6212.347703491249, 10110.342827716175, 10135.239857010607, 10199.796004080024, 10200.846987295048, 10210.97890210973, 9505.904940950502, 9856.310058296138, 10022.3485473444, 10068.748281293027, 10323.845142322722, 6915.273896241023, 7122.002919591365, 7317.3780228679025, 7490.737824176128, 7574.621268936421, 7374.963125184339, 7486.43851711113, 8285.751427457186, 8442.0, 8455.450395724258, 5599.412061733622, 6216.160818289473, 7003.6848341823725, 7120.857582848211, 7657.57883316423, 5896.380880007709, 7399.556026638419, 7410.0, 7427.591482468516, 7449.515781474187, 5969.582129250911, 6967.1291088613925, 6992.986017027604, 7008.684609192496, 7104.928950710427, 4620.953790462052, 4840.0, 4899.1426500363, 4977.129002424634, 4991.575716064175, 4617.868622187586, 5009.448960614257, 5058.974705126451, 5118.616103792156, 5138.97430512845], "value": 4455.0027909680075, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [239.63216462729707, 305.1455923414439, 307.96612372639004, 309.5650610891698, 315.3031799722613, 4443.1780120677695, 4544.1593305238475, 5384.488473595012, 5471.507564319206, 5505.311836020497, 4530.954690453094, 5309.522143007129, 5437.6465529740535, 5446.15584584389, 5475.206095116216, 3371.4774209997368, 3939.9803000985025, 4043.97978010111, 4083.8361067095884, 4119.40268661044, 3240.967590324102, 3537.0, 3832.2527107214105, 3920.0395903003805, 4112.712110152291, 3556.0, 3951.170254246612, 4036.3945408188715, 4166.708330416872, 4186.660268714013, 4001.7198796084285, 4125.50493940726, 4143.617419983491, 4171.998720307135, 4176.0, 3223.838808059611, 3571.8214089295693, 3575.570931488212, 3576.5708115026105, 3583.211693427442, 4318.935215971763, 5269.156934890438, 5361.624686271963, 5373.489518495737, 5377.838664840061, 4553.0, 4736.5026672199265, 4742.857714268543, 4796.71219726814, 4833.951660483385, 4740.454847692525, 5212.50481204285, 5731.019869667791, 5750.45370689788, 5802.65184088956, 3962.722609417342, 3977.6817854571855, 3977.9005524861823, 3984.163325701606, 3991.720579559432, 5004.149294619887, 5266.8939522700275, 5269.683818970874, 5290.576753859682, 5403.5136837684795, 3839.7504162229366, 3998.8400463981116, 4011.3982902564267, 4015.4980627421573, 4048.7570745755347, 4099.344104943195, 4108.26051310761, 4112.691548133902, 4115.42384066225, 4144.295469770146, 4743.501932297128, 4753.215719406259, 4770.976145119322, 4778.856634300907, 4780.880477988078, 5500.339959204921, 5754.539636829044, 5793.594448388572, 5798.797042103506, 5885.205497257815, 1367.746966811143, 1394.7280280345235, 1401.7056418152301, 1405.936732847004, 1407.324484247567, 741.3320029932652, 806.2944923192207, 852.2415050604967, 870.4516154822421, 872.2280781508284, 5143.408508021551, 5226.451222621646, 5272.789088436496, 5340.25236466895, 5380.435054319319, 5929.584929055011, 5976.0, 5988.371221021817, 5995.820125396245, 6031.276246850318, 4277.668670574544, 4563.703359281636, 5189.273501709764, 5494.395616482184, 5501.697406642593, 3081.4284714795617, 3113.626364836234, 3122.4379611669665, 3132.4024747379, 3144.3901561703733, 5722.971385143131, 6142.86357023943, 6189.35011823752, 6203.937960620427, 6283.622982621057, 5330.854795670573, 5818.941810581925, 5892.6759028253855, 6015.0, 6106.328303886569, 5524.447555244444, 5887.381824908409, 6007.897235967376, 6148.4773794227995, 6236.033414820619, 5040.117979353525, 5220.843374698765, 5590.189422533628, 5607.018771715015, 5969.641821490724, 4636.235021221461, 5486.945130548644, 5881.38245484218, 5884.205632239764, 5890.674598215367, 5350.8127215547265, 5406.83779486616, 6157.230346206724, 6192.225971753531, 6227.0, 3754.9061273468383, 4008.699347548887, 4084.5302790179508, 4113.4858142732155, 4120.21715873983, 5502.734371094591, 5719.456651618089, 5779.739911704068, 6010.519158467225, 6035.909461358171, 5622.746976385991, 5830.591858569859, 5847.210626565529, 5853.531717462508, 5873.825234952941, 5754.769809207753, 5762.193292938908, 5776.508996735324, 5780.421957804186, 5813.6221145625395, 4754.429468463806, 5690.260266165372, 5693.886122277614, 5708.4862362387585, 5730.3410107838135, 5495.450454954633, 5499.900019995936, 5669.34802497718, 5748.425157484218, 5809.970950145223, 4311.611954924072, 4496.797644106088, 5427.6472029317965, 5618.325800903917, 5659.717014149359], "value": 4175.094910607874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.5753782757708, 653.9411452969229, 729.901463302454, 785.858545461817, 818.3085292927478, 11517.0, 14925.0, 15962.680746385066, 15972.121533315663, 15983.760243596354, 12068.0, 14627.561173164795, 14651.413943442276, 14678.63303417415, 14863.702725945475, 697.5082566790426, 716.9426445884343, 853.2875049333813, 863.2964134230598, 880.4541184465609, 883.7348795361398, 885.4819930340726, 889.995550022249, 897.8428774964387, 898.4115404410089, 942.185009966381, 944.5371767833781, 945.5036106044313, 945.9830682016825, 946.1106559833769, 925.8148370325962, 928.424376886328, 929.1869614087673, 936.6300311377001, 938.2728385501223, 613.2058983616183, 684.0389253099403, 697.4072038767058, 698.322627051759, 700.1073631120312, 14502.202378869159, 14505.129692218501, 14512.564623061326, 14522.491712790108, 14571.417143314255, 14085.929570352186, 14243.581540430327, 14268.072575282677, 14286.071405358618, 14368.71262574753, 11762.470688818938, 11766.764664706743, 11833.822492662617, 11835.704107397298, 11907.821382679267, 3722.4788529605876, 3781.3949768037255, 3790.8294126764085, 3811.313963486571, 3912.3544615138467, 109592.0, 111446.32830507548, 114149.42925285404, 114617.4269128649, 116577.41711291474, 11066.633391564761, 11119.27724697892, 11122.443877806078, 11169.944150279196, 11354.205205635692, 22508.099676013102, 22512.887435563043, 22543.210987615355, 22551.887240563694, 22567.887160564092, 7914.0, 8016.238457346542, 8065.314448271845, 8256.215659512336, 8262.132476090044, 4303.63419109373, 4419.18245124653, 4518.977405112953, 4640.350350950869, 4664.906701865943, 3562.4656301554455, 3620.6922411594783, 3677.3013127505687, 3682.6317368262958, 3682.797446140488, 579.2151634535144, 641.0480436551717, 651.84681599824, 660.8579155481499, 673.1854456108066, 594.6105301027834, 625.744934445776, 635.2821311917504, 655.167936720372, 660.5079215984026, 9356.204722598519, 9412.294077944181, 9537.284703647254, 9558.617655293849, 9560.713178604654, 570.1277046119361, 635.5741653092439, 668.9632070236087, 678.3996163395332, 690.5821977703417, 3790.431435284674, 4377.080813029236, 4502.655361039471, 4539.795709193094, 4586.72479651222, 119517.8048219524, 133914.33042834722, 134949.0, 138395.0, 162439.37560624242, 82555.0, 115358.0, 117415.0, 120953.13804602672, 122193.9476960161, 101694.98305016855, 105814.0, 106506.46746766217, 107643.0, 110661.0, 9708.174805141642, 9916.702498925044, 9969.800603988024, 9998.400095994264, 10003.449810260505, 39269.60730392774, 51505.48494515157, 55143.72428137834, 59619.0, 61955.07067394083, 83875.5806220965, 87243.56378218069, 87991.56004219937, 90946.0, 93202.53398732963, 10746.677599672023, 10832.350058996486, 12967.41646625923, 13111.27887966171, 13613.52352667652, 14683.19242441676, 14728.852711472748, 14769.261536923326, 15372.38510459569, 15415.997960132556, 13481.0, 13563.704632028288, 13792.103513271606, 13869.791953120914, 14146.504872329419, 13519.46586327431, 13661.795073073714, 13704.588862334145, 13753.793693094813, 14012.369443374868, 12980.675483112998, 13482.52811151605, 13614.931925340707, 13676.0, 13794.103383280055, 10861.0, 11312.717182070184, 11339.149563782914, 11381.829272561085, 11393.601223957121, 8859.424137431046, 11000.559977600806, 11565.479553420284, 11581.594644187413, 11615.361155136548], "value": 7769.051240195388, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 117.0, 113.0, 114.0, 117.0, 118.0, 117.0, 117.0, 127.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 147.0, 109.0, 190.0, 150.0, 146.0, 121.0, 117.0, 144.0, 115.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 238.0, 270.0, 197.0, 196.0, 195.0, 195.0, 192.0, 195.0, 194.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 69.0, 88.0, 71.0, 86.0, 71.0, 60.0, 80.0, 58.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 86.0, 70.0, 70.0, 71.0, 125.0, 99.0, 101.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 87.0, 89.0, 86.0, 91.0, 96.0, 91.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 150.0, 97.0, 97.0, 97.0, 118.0, 124.0, 97.0, 98.0, 98.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 86.0, 87.0, 92.0, 88.0, 92.0, 90.0, 88.0, 90.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 57.0, 56.0, 62.0, 70.0, 72.0, 54.0, 59.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 103.0, 106.0, 122.0, 112.0, 69.0, 70.0, 69.0, 72.0, 71.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 89.0, 91.0, 75.0, 101.0, 103.0, 110.0, 114.0, 109.0], "value": 96.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 166.0, 161.0, 141.0, 162.0, 161.0, 161.0, 198.0, 158.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [248.0, 182.0, 181.0, 176.0, 172.0, 180.0, 209.0, 242.0, 172.0, 191.0], "value": 195.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 72.0, 73.0, 61.0, 72.0, 90.0, 91.0, 85.0, 68.0], "value": 75.1, "unit": "score"}]}]} 00:03:25 INFO - Return code: 0 00:03:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:03:25 ERROR - # TBPL SUCCESS # 00:03:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:03:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:03:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:03:25 INFO - 2016-10-19 00:03:25.236 screenresolution[1689:12871] starting screenresolution argv=screenresolution get 00:03:25 INFO - 2016-10-19 00:03:25.238 screenresolution[1689:12871] Display 0: 1600x1200x32@60 00:03:25 INFO - 2016-10-19 00:03:25.244 screenresolution[1690:12874] starting screenresolution argv=screenresolution list 00:03:25 INFO - Available Modes on Display 0 00:03:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:03:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:03:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:03:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:03:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:03:25 INFO - Intel Iris: 00:03:25 INFO - Chipset Model: Intel Iris 00:03:25 INFO - Type: GPU 00:03:25 INFO - Bus: Built-In 00:03:25 INFO - VRAM (Dynamic, Max): 1536 MB 00:03:25 INFO - Vendor: Intel (0x8086) 00:03:25 INFO - Device ID: 0x0a2e 00:03:25 INFO - Revision ID: 0x0009 00:03:25 INFO - Displays: 00:03:25 INFO - Display: 00:03:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:03:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:03:25 INFO - Display Serial Number: 1600x1200 60 00:03:25 INFO - Main Display: Yes 00:03:25 INFO - Mirror: Off 00:03:25 INFO - Online: Yes 00:03:25 INFO - Rotation: Supported 00:03:25 INFO - Return code: 0 00:03:25 INFO - Running post-action listener: _resource_record_post_action 00:03:25 INFO - [mozharness: 2016-10-19 07:03:25.480328Z] Finished run-tests step (success) 00:03:25 INFO - Running post-run listener: _resource_record_post_run 00:03:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38818304; Write bytes: 726086656; Read time: 338; Write time: 2120 00:03:25 INFO - TinderboxPrint: CPU usage
54.3% 00:03:25 INFO - TinderboxPrint: I/O read bytes / time
38,818,304 / 338 00:03:25 INFO - TinderboxPrint: I/O write bytes / time
726,086,656 / 2,120 00:03:25 INFO - TinderboxPrint: CPU idle
2,214.7 (45.7%) 00:03:25 INFO - TinderboxPrint: CPU system
980.7 (20.2%) 00:03:25 INFO - TinderboxPrint: CPU user
1,654.4 (34.1%) 00:03:25 INFO - TinderboxPrint: Swap in / out
437,637,120 / 0 00:03:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397615616; Write bytes: 391108096; Read time: 29331; Write time: 812 00:03:25 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36717056; Write bytes: 316591616; Read time: 317; Write time: 1255 00:03:25 INFO - Running post-run listener: _upload_blobber_files 00:03:25 INFO - Blob upload gear active. 00:03:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:03:25 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 00:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:03:26 INFO - (blobuploader) - INFO - Open directory for files ... 00:03:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:03:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:03:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:27 INFO - (blobuploader) - INFO - Done attempting. 00:03:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:03:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:03:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:28 INFO - (blobuploader) - INFO - Done attempting. 00:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:03:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:03:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:31 INFO - (blobuploader) - INFO - Done attempting. 00:03:31 INFO - (blobuploader) - INFO - Iteration through files over. 00:03:31 INFO - Return code: 0 00:03:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:03:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:03:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d7280794707e3d449f51e8540268741da7c0515a535a1db5d4cc9bd863de0eae5c7f1ad42a17b354247db3bee80c361609a51be49009baffa190302da2e8384", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d30bc1677bcd6746dd48eec2fac8cfcce803542cc68865a8b378097fd33ff2d2c654099fa2ebf1462ee1ca0a662b59c99d8d5ac92d047806ace34e4b0b03f58d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/921d9de524d365e4334ef4eb935f361335d1f88c944058789604a56112e0a7742414f0761f2b328a5a5933db01c3fa83fb4da1ad5a8b338d73210b6a89d8a489"} 00:03:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:03:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:03:31 INFO - Contents: 00:03:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d7280794707e3d449f51e8540268741da7c0515a535a1db5d4cc9bd863de0eae5c7f1ad42a17b354247db3bee80c361609a51be49009baffa190302da2e8384", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d30bc1677bcd6746dd48eec2fac8cfcce803542cc68865a8b378097fd33ff2d2c654099fa2ebf1462ee1ca0a662b59c99d8d5ac92d047806ace34e4b0b03f58d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/921d9de524d365e4334ef4eb935f361335d1f88c944058789604a56112e0a7742414f0761f2b328a5a5933db01c3fa83fb4da1ad5a8b338d73210b6a89d8a489"} 00:03:31 INFO - Running post-run listener: copy_logs_to_upload_dir 00:03:31 INFO - Copying logs to upload dir... 00:03:31 INFO - mkdir: /builds/slave/test/build/upload/logs 00:03:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.224664 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-19 00:03:31.284371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 00:03:31.287889) ========= 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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d7280794707e3d449f51e8540268741da7c0515a535a1db5d4cc9bd863de0eae5c7f1ad42a17b354247db3bee80c361609a51be49009baffa190302da2e8384", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d30bc1677bcd6746dd48eec2fac8cfcce803542cc68865a8b378097fd33ff2d2c654099fa2ebf1462ee1ca0a662b59c99d8d5ac92d047806ace34e4b0b03f58d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/921d9de524d365e4334ef4eb935f361335d1f88c944058789604a56112e0a7742414f0761f2b328a5a5933db01c3fa83fb4da1ad5a8b338d73210b6a89d8a489"} build_url:https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/fx9zBomAQS2sdCYfoNVonQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d7280794707e3d449f51e8540268741da7c0515a535a1db5d4cc9bd863de0eae5c7f1ad42a17b354247db3bee80c361609a51be49009baffa190302da2e8384", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d30bc1677bcd6746dd48eec2fac8cfcce803542cc68865a8b378097fd33ff2d2c654099fa2ebf1462ee1ca0a662b59c99d8d5ac92d047806ace34e4b0b03f58d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/921d9de524d365e4334ef4eb935f361335d1f88c944058789604a56112e0a7742414f0761f2b328a5a5933db01c3fa83fb4da1ad5a8b338d73210b6a89d8a489"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 00:03:31.320867) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 00:03:31.321248) ========= 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.nwjtZv8oz0/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.CyZWIQQrsZ/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.004580 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 00:03:31.369419) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 00:03:31.369729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 00:03:31.370047) ========= ========= Total master_lag: 0.20 =========