builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0272 starttime: 1476385982.9 results: success (0) buildid: 20161013093024 builduid: edc515266e094507ab0fb9367024ef42 revision: 83461556cda59e8bcd54f09b9263e3202ce01dc2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:02.903558) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:02.903995) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:02.904267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:02.929498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:02.929916) ========= 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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-13 12:13:02-- 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% 19.1M=0.001s 2016-10-13 12:13:03 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140516 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:03.093193) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:03.093500) ========= 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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.025751 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:03.141405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:03.141737) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 83461556cda59e8bcd54f09b9263e3202ce01dc2 --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 83461556cda59e8bcd54f09b9263e3202ce01dc2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-13 12:13:03,226 truncating revision to first 12 chars 2016-10-13 12:13:03,226 Setting DEBUG logging. 2016-10-13 12:13:03,226 attempt 1/10 2016-10-13 12:13:03,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/83461556cda5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 12:13:03,881 unpacking tar archive at: fx-team-83461556cda5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:04.108390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:04.108710) ========= script_repo_revision: 83461556cda59e8bcd54f09b9263e3202ce01dc2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:04.109101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:04.109359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:13:04.128088) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-13 12:13:04.128399) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:13:04 INFO - MultiFileLogger online at 20161013 12:13:04 in /builds/slave/test 12:13:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:13:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:04 INFO - {'append_to_log': False, 12:13:04 INFO - 'base_work_dir': '/builds/slave/test', 12:13:04 INFO - 'blob_upload_branch': 'Fx-Team', 12:13:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:04 INFO - 'branch': 'Fx-Team', 12:13:04 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:04 INFO - 'config_files': ('talos/mac_config.py',), 12:13:04 INFO - 'default_actions': ('clobber', 12:13:04 INFO - 'read-buildbot-config', 12:13:04 INFO - 'download-and-extract', 12:13:04 INFO - 'populate-webroot', 12:13:04 INFO - 'create-virtualenv', 12:13:04 INFO - 'install', 12:13:04 INFO - 'run-tests'), 12:13:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:04 INFO - 'download_minidump_stackwalk': True, 12:13:04 INFO - 'download_symbols': 'ondemand', 12:13:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:04 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:04 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:04 INFO - 'installer_path': 'installer.exe', 12:13:04 INFO - 'log_level': 'info', 12:13:04 INFO - 'log_name': 'talos', 12:13:04 INFO - 'log_to_console': True, 12:13:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:13:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:13:04 INFO - 'opt_config_files': (), 12:13:04 INFO - 'pip_index': False, 12:13:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:04 INFO - 'cmd': ('bash', 12:13:04 INFO - '-c', 12:13:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:04 INFO - 'enabled': True, 12:13:04 INFO - 'halt_on_failure': False, 12:13:04 INFO - 'name': 'check_screen_resolution'},), 12:13:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:04 INFO - 'cmd': ('bash', 12:13:04 INFO - '-c', 12:13:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:04 INFO - 'enabled': True, 12:13:04 INFO - 'halt_on_failure': False, 12:13:04 INFO - 'name': 'check_screen_resolution'},), 12:13:04 INFO - 'run_cmd_checks_enabled': True, 12:13:04 INFO - 'sps_profile': False, 12:13:04 INFO - 'sps_profile_interval': 0, 12:13:04 INFO - 'suite': 'dromaeojs', 12:13:04 INFO - 'system_bits': '32', 12:13:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:04 INFO - 'title': 't-yosemite-r7-0272', 12:13:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:04 INFO - 'use_talos_json': True, 12:13:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:04 INFO - 'work_dir': 'build'} 12:13:04 INFO - [mozharness: 2016-10-13 19:13:04.350118Z] Running clobber step. 12:13:04 INFO - Running pre-action listener: _resource_record_pre_action 12:13:04 INFO - Running main action method: clobber 12:13:04 INFO - rmtree: /builds/slave/test/build 12:13:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:06 INFO - Running post-action listener: _resource_record_post_action 12:13:06 INFO - [mozharness: 2016-10-13 19:13:06.563379Z] Finished clobber step (success) 12:13:06 INFO - [mozharness: 2016-10-13 19:13:06.563506Z] Running read-buildbot-config step. 12:13:06 INFO - Running pre-action listener: _resource_record_pre_action 12:13:06 INFO - Running main action method: read_buildbot_config 12:13:06 INFO - Using buildbot properties: 12:13:06 INFO - { 12:13:06 INFO - "project": "", 12:13:06 INFO - "product": "firefox", 12:13:06 INFO - "script_repo_revision": "production", 12:13:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:13:06 INFO - "repository": "", 12:13:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:13:06 INFO - "buildid": "20161013093024", 12:13:06 INFO - "pgo_build": "False", 12:13:06 INFO - "basedir": "/builds/slave/test", 12:13:06 INFO - "buildnumber": 7, 12:13:06 INFO - "slavename": "t-yosemite-r7-0272", 12:13:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:13:06 INFO - "platform": "yosemite_r7", 12:13:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:13:06 INFO - "branch": "fx-team", 12:13:06 INFO - "repo_path": "integration/fx-team", 12:13:06 INFO - "revision": "83461556cda59e8bcd54f09b9263e3202ce01dc2", 12:13:06 INFO - "stage_platform": "macosx64", 12:13:06 INFO - "builduid": "edc515266e094507ab0fb9367024ef42", 12:13:06 INFO - "slavebuilddir": "test" 12:13:06 INFO - } 12:13:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:13:06 INFO - Running post-action listener: _resource_record_post_action 12:13:06 INFO - [mozharness: 2016-10-13 19:13:06.567053Z] Finished read-buildbot-config step (success) 12:13:06 INFO - [mozharness: 2016-10-13 19:13:06.567154Z] Running download-and-extract step. 12:13:06 INFO - Running pre-action listener: _resource_record_pre_action 12:13:06 INFO - Running main action method: download_and_extract 12:13:06 INFO - mkdir: /builds/slave/test/build/tests 12:13: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')]} 12:13:06 INFO - trying https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:13:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:13:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:13:07 INFO - Downloaded 1199 bytes. 12:13:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:13:07 INFO - Using the following test package requirements: 12:13:07 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:13:07 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:13:07 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:13:07 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'jsshell-mac.zip'], 12:13:07 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:13:07 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:13:07 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:13:07 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:13:07 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:13:07 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:13:07 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:13:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:13:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:13:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:13:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:13:08 INFO - Http code: 200 12:13:08 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 12:13:08 INFO - x-amz-cf-id: 7PNezrRn_Kn9-c8GwzY5O9FnFcKRESUp6VPJn9Eph8gZ7dtgYUHeQg== 12:13:08 INFO - x-amz-version-id: WqqRR498kP6PIpWVZuT9lZFmBfotoyXu 12:13:08 INFO - Expected file size: 16520324 12:13:08 INFO - Obtained file size: 16520324 12:13:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:13:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:13:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:13:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:13:16 INFO - Http code: 200 12:13:16 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 12:13:16 INFO - x-amz-cf-id: oev-H1j2Db_5sPSCAOT2DmRXR1EPra8OrMVQtOk80if7le2flOcW3w== 12:13:16 INFO - x-amz-version-id: WqqRR498kP6PIpWVZuT9lZFmBfotoyXu 12:13:16 INFO - Expected file size: 16520324 12:13:16 INFO - Obtained file size: 16520324 12:13:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:13:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:13:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:13:23 INFO - Http code: 200 12:13:23 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 12:13:23 INFO - x-amz-cf-id: -vNGibmYC80873LNmECYk-Cqbu8vBk5Q_OB_GsIhJDs1iH1i7hCXcA== 12:13:23 INFO - x-amz-version-id: V775f4VV7QAMe4cspApdailTJO.H89Dh 12:13:24 INFO - Expected file size: 14409006 12:13:24 INFO - Obtained file size: 14409006 12:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:24 INFO - trying https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:13:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:13:27 INFO - Downloaded 111660779 bytes. 12:13:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:13:27 INFO - mkdir: /builds/slave/test/properties 12:13:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:27 INFO - Contents: 12:13:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:13:27 INFO - Running post-action listener: _resource_record_post_action 12:13:27 INFO - Running post-action listener: set_extra_try_arguments 12:13:27 INFO - [mozharness: 2016-10-13 19:13:27.827033Z] Finished download-and-extract step (success) 12:13:27 INFO - [mozharness: 2016-10-13 19:13:27.827145Z] Running populate-webroot step. 12:13:27 INFO - Running pre-action listener: _resource_record_pre_action 12:13:27 INFO - Running main action method: populate_webroot 12:13:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:27 INFO - '--tppagecycles', 12:13:27 INFO - '7'], 12:13:27 INFO - 'tests': ['tsvgm']}}, 12:13:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['damp', 'tps']}, 12:13:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'tests': ['damp', 'tps']}, 12:13:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['dromaeo_dom']}, 12:13:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['basic_compositor_video']}, 12:13:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['a11yr', 12:13:27 INFO - 'ts_paint', 12:13:27 INFO - 'tpaint', 12:13:27 INFO - 'sessionrestore', 12:13:27 INFO - 'sessionrestore_no_auto_restore', 12:13:27 INFO - 'tabpaint']}, 12:13:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['tsvgx', 12:13:27 INFO - 'tsvgr_opacity', 12:13:27 INFO - 'tart', 12:13:27 INFO - 'tscrollx', 12:13:27 INFO - 'cart']}, 12:13:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:27 INFO - 'tsvgr_opacity', 12:13:27 INFO - 'tart', 12:13:27 INFO - 'tscrollx', 12:13:27 INFO - 'cart']}, 12:13:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'talos_options': ['--disable-e10s'], 12:13:27 INFO - 'tests': ['tp5o']}, 12:13:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'tests': ['tp5o']}, 12:13:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'talos_options': ['--disable-e10s', 12:13:27 INFO - '--xperf_path', 12:13:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:27 INFO - 'tests': ['tp5n']}, 12:13:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:27 INFO - 'talos_options': ['--xperf_path', 12:13:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:27 INFO - 'tests': ['tp5n']}}, 12:13:27 INFO - 'talos.zip': {'path': '', 12:13:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:27 INFO - Running post-action listener: _resource_record_post_action 12:13:27 INFO - [mozharness: 2016-10-13 19:13:27.845531Z] Finished populate-webroot step (success) 12:13:27 INFO - [mozharness: 2016-10-13 19:13:27.845660Z] Running create-virtualenv step. 12:13:27 INFO - Running pre-action listener: _resource_record_pre_action 12:13:27 INFO - Running main action method: create_virtualenv 12:13:27 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:13:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:13:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:13:27 INFO - Using real prefix '/tools/python27' 12:13:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:13:28 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:31 INFO - Installing pip.................done. 12:13:31 INFO - Return code: 0 12:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:13:31 INFO - Reading from file tmpfile_stdout 12:13:31 INFO - Output received: 12:13:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:13:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:13:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:31 INFO - 'HOME': '/Users/cltbld', 12:13:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:31 INFO - 'LOGNAME': 'cltbld', 12:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:31 INFO - 'MOZ_NO_REMOTE': '1', 12:13:31 INFO - 'NO_EM_RESTART': '1', 12:13:31 INFO - 'PAGER': '/bin/cat', 12:13:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:31 INFO - 'PWD': '/builds/slave/test', 12:13:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:31 INFO - 'SHELL': '/bin/bash', 12:13:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:31 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:31 INFO - 'USER': 'cltbld', 12:13:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:31 INFO - 'XPC_FLAGS': '0x0', 12:13:31 INFO - 'XPC_SERVICE_NAME': '0', 12:13:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:13:32 INFO - Cleaning up... 12:13:32 INFO - Return code: 0 12:13:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:32 INFO - 'HOME': '/Users/cltbld', 12:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:32 INFO - 'LOGNAME': 'cltbld', 12:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:32 INFO - 'MOZ_NO_REMOTE': '1', 12:13:32 INFO - 'NO_EM_RESTART': '1', 12:13:32 INFO - 'PAGER': '/bin/cat', 12:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:32 INFO - 'PWD': '/builds/slave/test', 12:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:32 INFO - 'SHELL': '/bin/bash', 12:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:32 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:32 INFO - 'USER': 'cltbld', 12:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:32 INFO - 'XPC_FLAGS': '0x0', 12:13:32 INFO - 'XPC_SERVICE_NAME': '0', 12:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:32 INFO - Downloading/unpacking psutil>=3.1.1 12:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:35 INFO - Installing collected packages: psutil 12:13:35 INFO - Running setup.py install for psutil 12:13:35 INFO - building 'psutil._psutil_osx' extension 12:13:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:13:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:13:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:13:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:13:36 INFO - building 'psutil._psutil_posix' extension 12:13:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:13:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:13:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:13:36 INFO - ^ 12:13:36 INFO - 1 warning generated. 12:13:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:13:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:36 INFO - Successfully installed psutil 12:13:36 INFO - Cleaning up... 12:13:36 INFO - Return code: 0 12:13:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:36 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:13:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:36 INFO - 'HOME': '/Users/cltbld', 12:13:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:36 INFO - 'LOGNAME': 'cltbld', 12:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:36 INFO - 'MOZ_NO_REMOTE': '1', 12:13:36 INFO - 'NO_EM_RESTART': '1', 12:13:36 INFO - 'PAGER': '/bin/cat', 12:13:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:36 INFO - 'PWD': '/builds/slave/test', 12:13:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:36 INFO - 'SHELL': '/bin/bash', 12:13:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:36 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:36 INFO - 'USER': 'cltbld', 12:13:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:36 INFO - 'XPC_FLAGS': '0x0', 12:13:36 INFO - 'XPC_SERVICE_NAME': '0', 12:13:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:39 INFO - Installing collected packages: mozsystemmonitor 12:13:39 INFO - Running setup.py install for mozsystemmonitor 12:13:39 INFO - Successfully installed mozsystemmonitor 12:13:39 INFO - Cleaning up... 12:13:40 INFO - Return code: 0 12:13:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:40 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:40 INFO - 'HOME': '/Users/cltbld', 12:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:40 INFO - 'LOGNAME': 'cltbld', 12:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:40 INFO - 'MOZ_NO_REMOTE': '1', 12:13:40 INFO - 'NO_EM_RESTART': '1', 12:13:40 INFO - 'PAGER': '/bin/cat', 12:13:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:40 INFO - 'PWD': '/builds/slave/test', 12:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:40 INFO - 'SHELL': '/bin/bash', 12:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:40 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:40 INFO - 'USER': 'cltbld', 12:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:40 INFO - 'XPC_FLAGS': '0x0', 12:13:40 INFO - 'XPC_SERVICE_NAME': '0', 12:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:40 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:43 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:43 INFO - warning: no files found matching '*.txt' 12:13:43 INFO - no previously-included directories found matching 'build' 12:13:43 INFO - no previously-included directories found matching 'dist' 12:13:43 INFO - no previously-included directories found matching '.git*' 12:13:43 INFO - Installing collected packages: jsonschema, functools32 12:13:43 INFO - Running setup.py install for functools32 12:13:43 INFO - warning: no files found matching '*.txt' 12:13:43 INFO - no previously-included directories found matching 'build' 12:13:43 INFO - no previously-included directories found matching 'dist' 12:13:43 INFO - no previously-included directories found matching '.git*' 12:13:43 INFO - Successfully installed jsonschema functools32 12:13:43 INFO - Cleaning up... 12:13:43 INFO - Return code: 0 12:13:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:43 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:43 INFO - 'HOME': '/Users/cltbld', 12:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:43 INFO - 'LOGNAME': 'cltbld', 12:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:43 INFO - 'MOZ_NO_REMOTE': '1', 12:13:43 INFO - 'NO_EM_RESTART': '1', 12:13:43 INFO - 'PAGER': '/bin/cat', 12:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:43 INFO - 'PWD': '/builds/slave/test', 12:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:43 INFO - 'SHELL': '/bin/bash', 12:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:43 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:43 INFO - 'USER': 'cltbld', 12:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:43 INFO - 'XPC_FLAGS': '0x0', 12:13:43 INFO - 'XPC_SERVICE_NAME': '0', 12:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:13:43 INFO - Cleaning up... 12:13:43 INFO - Return code: 0 12:13:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:43 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:43 INFO - 'HOME': '/Users/cltbld', 12:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:43 INFO - 'LOGNAME': 'cltbld', 12:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:43 INFO - 'MOZ_NO_REMOTE': '1', 12:13:43 INFO - 'NO_EM_RESTART': '1', 12:13:43 INFO - 'PAGER': '/bin/cat', 12:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:43 INFO - 'PWD': '/builds/slave/test', 12:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:43 INFO - 'SHELL': '/bin/bash', 12:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:43 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:43 INFO - 'USER': 'cltbld', 12:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:43 INFO - 'XPC_FLAGS': '0x0', 12:13:43 INFO - 'XPC_SERVICE_NAME': '0', 12:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:44 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:46 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:47 INFO - Downloading docopt-0.6.1.tar.gz 12:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:48 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:48 INFO - Running setup.py install for blobuploader 12:13:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:48 INFO - Running setup.py install for requests 12:13:48 INFO - Running setup.py install for docopt 12:13:48 INFO - Successfully installed blobuploader requests docopt 12:13:48 INFO - Cleaning up... 12:13:48 INFO - Return code: 0 12:13:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:48 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:48 INFO - 'HOME': '/Users/cltbld', 12:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:48 INFO - 'LOGNAME': 'cltbld', 12:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:48 INFO - 'MOZ_NO_REMOTE': '1', 12:13:48 INFO - 'NO_EM_RESTART': '1', 12:13:48 INFO - 'PAGER': '/bin/cat', 12:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:48 INFO - 'PWD': '/builds/slave/test', 12:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:48 INFO - 'SHELL': '/bin/bash', 12:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:48 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:48 INFO - 'USER': 'cltbld', 12:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:48 INFO - 'XPC_FLAGS': '0x0', 12:13:48 INFO - 'XPC_SERVICE_NAME': '0', 12:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-hdnM5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-N51Uiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-h69rnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-pZChrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-FWAkvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-U2s32z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-CcwJXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-adjV15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:49 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-69a3rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-HzPhRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-56Gf7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-9wHO1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-eOvQne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-_zDiU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-YNKSlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-RdJYq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:50 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-LciJQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:50 INFO - Running setup.py install for manifestparser 12:13:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:51 INFO - Running setup.py install for mozcrash 12:13:51 INFO - Running setup.py install for mozdebug 12:13:51 INFO - Running setup.py install for mozdevice 12:13:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:51 INFO - Running setup.py install for mozfile 12:13:51 INFO - Running setup.py install for mozhttpd 12:13:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:51 INFO - Running setup.py install for mozinfo 12:13:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:51 INFO - Running setup.py install for mozInstall 12:13:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Running setup.py install for mozleak 12:13:52 INFO - Running setup.py install for mozlog 12:13:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Running setup.py install for moznetwork 12:13:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Running setup.py install for mozprocess 12:13:52 INFO - Running setup.py install for mozprofile 12:13:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:52 INFO - Running setup.py install for mozrunner 12:13:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:53 INFO - Running setup.py install for mozscreenshot 12:13:53 INFO - Running setup.py install for moztest 12:13:53 INFO - Running setup.py install for mozversion 12:13:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:53 INFO - Cleaning up... 12:13:53 INFO - Return code: 0 12:13:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:54 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:54 INFO - 'HOME': '/Users/cltbld', 12:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:54 INFO - 'LOGNAME': 'cltbld', 12:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:54 INFO - 'MOZ_NO_REMOTE': '1', 12:13:54 INFO - 'NO_EM_RESTART': '1', 12:13:54 INFO - 'PAGER': '/bin/cat', 12:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:54 INFO - 'PWD': '/builds/slave/test', 12:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:54 INFO - 'SHELL': '/bin/bash', 12:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:54 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:54 INFO - 'USER': 'cltbld', 12:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:54 INFO - 'XPC_FLAGS': '0x0', 12:13:54 INFO - 'XPC_SERVICE_NAME': '0', 12:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:54 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ZGWNbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:54 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-fzc1ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:54 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-tonUPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:54 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-9zFrIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:54 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-A2L8Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-O2ST9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-CnuLSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-kAOLDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-mlytMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-VXmmBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-L5MIdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-J40HFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jTGXYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:55 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-HHiQwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Fp9Iea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-TLvxSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-iIgIqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:13:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13:59 INFO - Downloading blessings-1.6.tar.gz 12:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:59 INFO - Installing collected packages: blessings 12:13:59 INFO - Running setup.py install for blessings 12:13:59 INFO - Successfully installed blessings 12:13:59 INFO - Cleaning up... 12:13:59 INFO - Return code: 0 12:13:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:59 INFO - Reading from file tmpfile_stdout 12:13:59 INFO - Current package versions: 12:13:59 INFO - blessings == 1.6 12:13:59 INFO - blobuploader == 1.2.4 12:13:59 INFO - docopt == 0.6.1 12:13:59 INFO - functools32 == 3.2.3-2 12:13:59 INFO - jsonschema == 2.5.1 12:13:59 INFO - manifestparser == 1.1 12:13:59 INFO - mozInstall == 1.12 12:13:59 INFO - mozcrash == 0.17 12:13:59 INFO - mozdebug == 0.1 12:13:59 INFO - mozdevice == 0.48 12:13:59 INFO - mozfile == 1.2 12:13:59 INFO - mozhttpd == 0.7 12:13:59 INFO - mozinfo == 0.9 12:13:59 INFO - mozleak == 0.1 12:13:59 INFO - mozlog == 3.4 12:13:59 INFO - moznetwork == 0.27 12:13:59 INFO - mozprocess == 0.23 12:13:59 INFO - mozprofile == 0.28 12:13:59 INFO - mozrunner == 6.12 12:13:59 INFO - mozscreenshot == 0.1 12:13:59 INFO - mozsystemmonitor == 0.3 12:13:59 INFO - moztest == 0.7 12:13:59 INFO - mozversion == 1.4 12:13:59 INFO - psutil == 3.1.1 12:13:59 INFO - requests == 1.2.3 12:13:59 INFO - wsgiref == 0.1.2 12:13:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:59 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 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:13:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:13:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:59 INFO - 'HOME': '/Users/cltbld', 12:13:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:59 INFO - 'LOGNAME': 'cltbld', 12:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:59 INFO - 'MOZ_NO_REMOTE': '1', 12:13:59 INFO - 'NO_EM_RESTART': '1', 12:13:59 INFO - 'PAGER': '/bin/cat', 12:13:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:59 INFO - 'PWD': '/builds/slave/test', 12:13:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:59 INFO - 'SHELL': '/bin/bash', 12:13:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:13:59 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:13:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:59 INFO - 'USER': 'cltbld', 12:13:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:59 INFO - 'XPC_FLAGS': '0x0', 12:13:59 INFO - 'XPC_SERVICE_NAME': '0', 12:13:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:14:00 INFO - Cleaning up... 12:14:00 INFO - Return code: 0 12:14:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d783490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb6bd90a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, '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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:00 INFO - 'HOME': '/Users/cltbld', 12:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:00 INFO - 'LOGNAME': 'cltbld', 12:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:00 INFO - 'MOZ_NO_REMOTE': '1', 12:14:00 INFO - 'NO_EM_RESTART': '1', 12:14:00 INFO - 'PAGER': '/bin/cat', 12:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:00 INFO - 'PWD': '/builds/slave/test', 12:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:00 INFO - 'SHELL': '/bin/bash', 12:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:14:00 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:00 INFO - 'USER': 'cltbld', 12:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:00 INFO - 'XPC_FLAGS': '0x0', 12:14:00 INFO - 'XPC_SERVICE_NAME': '0', 12:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:00 INFO - Cleaning up... 12:14:00 INFO - Return code: 0 12:14:00 INFO - Running post-action listener: _resource_record_post_action 12:14:00 INFO - Running post-action listener: _start_resource_monitoring 12:14:00 INFO - Starting resource monitoring. 12:14:00 INFO - [mozharness: 2016-10-13 19:14:00.428431Z] Finished create-virtualenv step (success) 12:14:00 INFO - [mozharness: 2016-10-13 19:14:00.429486Z] Running install step. 12:14:00 INFO - Running pre-action listener: _resource_record_pre_action 12:14:00 INFO - Running main action method: install 12:14:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:00 INFO - Reading from file tmpfile_stdout 12:14:00 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:00 INFO - Reading from file tmpfile_stdout 12:14:00 INFO - Output received: 12:14:00 INFO - Usage: mozinstall [options] installer 12:14:00 INFO - Options: 12:14:00 INFO - -h, --help show this help message and exit 12:14:00 INFO - -d DEST, --destination=DEST 12:14:00 INFO - Directory to install application into. [default: 12:14:00 INFO - "/builds/slave/test"] 12:14:00 INFO - --app=APP Application being installed. [default: firefox] 12:14:00 INFO - mkdir: /builds/slave/test/build/application 12:14:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:14:36 INFO - Reading from file tmpfile_stdout 12:14:36 INFO - Output received: 12:14:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:14:36 INFO - Running post-action listener: _resource_record_post_action 12:14:36 INFO - [mozharness: 2016-10-13 19:14:36.423938Z] Finished install step (success) 12:14:36 INFO - [mozharness: 2016-10-13 19:14:36.424065Z] Running run-tests step. 12:14:36 INFO - Running pre-action listener: _resource_record_pre_action 12:14:36 INFO - Running main action method: run_tests 12:14:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:36 INFO - 2016-10-13 12:14:36.438 screenresolution[1646:7667] starting screenresolution argv=screenresolution get 12:14:36 INFO - 2016-10-13 12:14:36.441 screenresolution[1646:7667] Display 0: 1600x1200x32@60 12:14:36 INFO - 2016-10-13 12:14:36.447 screenresolution[1647:7669] starting screenresolution argv=screenresolution list 12:14:36 INFO - Available Modes on Display 0 12:14:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:36 INFO - Intel Iris: 12:14:36 INFO - Chipset Model: Intel Iris 12:14:36 INFO - Type: GPU 12:14:36 INFO - Bus: Built-In 12:14:36 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:36 INFO - Vendor: Intel (0x8086) 12:14:36 INFO - Device ID: 0x0a2e 12:14:36 INFO - Revision ID: 0x0009 12:14:36 INFO - Displays: 12:14:36 INFO - Display: 12:14:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:36 INFO - Display Serial Number: 1600x1200 60 12:14:36 INFO - Main Display: Yes 12:14:36 INFO - Mirror: Off 12:14:36 INFO - Online: Yes 12:14:36 INFO - Rotation: Supported 12:14:36 INFO - Return code: 0 12:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:36 INFO - Python 2.7.3 12:14:36 INFO - Return code: 0 12:14:36 INFO - grabbing minidump binary from tooltool 12:14: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')]} 12:14:36 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 0x10d597d30>, '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 0x10d3bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d58fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:14:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:14:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:14:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:36 INFO - Return code: 0 12:14:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:14:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:14:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:36 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:14:36 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0272 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnBCpXrNP2/Render', 12:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:36 INFO - 'HOME': '/Users/cltbld', 12:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:36 INFO - 'LOGNAME': 'cltbld', 12:14:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:36 INFO - 'MOZ_NO_REMOTE': '1', 12:14:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:36 INFO - 'NO_EM_RESTART': '1', 12:14:36 INFO - 'PAGER': '/bin/cat', 12:14:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:36 INFO - 'PWD': '/builds/slave/test', 12:14:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:36 INFO - 'SHELL': '/bin/bash', 12:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tUfEPqmFIy/Listeners', 12:14:36 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 12:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:36 INFO - 'USER': 'cltbld', 12:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:36 INFO - 'XPC_FLAGS': '0x0', 12:14:36 INFO - 'XPC_SERVICE_NAME': '0', 12:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:14:37 INFO - mozversion application_buildid: 20161013093024 12:14:37 INFO - mozversion application_changeset: 83461556cda59e8bcd54f09b9263e3202ce01dc2 12:14:37 INFO - mozversion application_display_name: Nightly 12:14:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:37 INFO - mozversion application_name: Firefox 12:14:37 INFO - mozversion application_remotingname: firefox 12:14:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:14:37 INFO - mozversion application_vendor: Mozilla 12:14:37 INFO - mozversion application_version: 52.0a1 12:14:37 INFO - mozversion platform_buildid: 20161013093024 12:14:37 INFO - mozversion platform_changeset: 83461556cda59e8bcd54f09b9263e3202ce01dc2 12:14:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:14:37 INFO - mozversion platform_version: 52.0a1 12:14:37 INFO - using testdate: 1476386077 12:14:37 INFO - actual date: 1476386077 12:14:37 INFO - starting webserver on 'localhost:49274' 12:14:37 INFO - SUITE-START | Running 2 tests 12:14:37 INFO - TEST-START | dromaeo_css 12:14:37 INFO - operating with platform_type : mac_ 12:14:37 INFO - Initialising browser for dromaeo_css test... 12:14:37 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp491B86/profile http://localhost:49274/getInfo.html) 12:14:39 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 12:14:39 INFO - PROCESS | 1657 | colorDepth:24 12:14:39 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 12:14:39 INFO - PROCESS | 1657 | __metrics 12:14:39 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:14:39 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:40 INFO - TEST-INFO | 1657: exit 0 12:14:40 INFO - Browser initialized. 12:14:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:40 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp491B86/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:52 INFO - PROCESS | 1659 | RSS: Main: 236945408 12:14:52 INFO - PROCESS | 1659 | 12:15:43 INFO - PROCESS | 1659 | 1476386143729 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:17:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 12:17:58 INFO - PROCESS | 1659 | RSS: Main: 401362944 12:17:58 INFO - PROCESS | 1659 | 12:21:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 12:21:01 INFO - PROCESS | 1659 | RSS: Main: 377716736 12:21:01 INFO - PROCESS | 1659 | 12:24:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 12:24:04 INFO - PROCESS | 1659 | RSS: Main: 340258816 12:24:04 INFO - PROCESS | 1659 | 12:27:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 12:27:07 INFO - PROCESS | 1659 | RSS: Main: 334381056 12:27:07 INFO - PROCESS | 1659 | 12:30:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 12:30:11 INFO - PROCESS | 1659 | RSS: Main: 330387456 12:30:11 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 12:33:15 INFO - PROCESS | 1659 | RSS: Main: 397938688 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | __start_tp_report 12:33:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 12:33:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 12:33:15 INFO - PROCESS | 1659 | |i|pagename|runs| 12:33:15 INFO - PROCESS | 1659 | |0;dojo.html;2956.512175491044;3314.254292784123;3322.7009569138786;3350.5476760637307;3381.492776083587;5479.0959491683925;5525.033119204143;5544.112941929287;5622.987862184805;5632.408597097307;4617.468991066028;5542;5565.2486914266565;5578.609497335178;5580.107182850745;5032;5073.239014147889;5085.033843569729;5118.923216151761;5180.041692286939;5090.211017292307;5176.145935920568;5181.1192097343355;5182.611304152185;5184.377874655047;5079.923801142986;5115.514026167508;5123.666961647499;5127.2052831811125;5154.355705536808;5208.583313334924;5211.374635043819;5211.895762084775;5218.0607490651855;5269.262303277545;5017.89964200714;5021.121303771825;5065.645404821664;5119.104156772587;5132.948670513284;5490.313710786152;5570.220169176318;5597.132444471114;5605;5640.605157638967;6162.7534898604;6177.845553861144;6181.041938499539;6183.907241391383;6192.8761424771255;6030.427109424598;6122.602030867979;6185.319614842364;6242.719077641517;6258.280297765769;5660.2075709400715;5712.371639119693;5713.143028545752;5728.656280623176;5757.8560535986935;6292.244930608356;6307.117003619497;6309.495240380758;6330.746770129143;6332.746690132343;5894.17481552583;5979.730912108966;6003.669798161143;6039.857076649423;6087.421694938974;6106.786262480792;6138.386161383826;6183.443490085914;6183.783567575113;6224.408681175281;6152.415520525543;6159.353267906895;6159.6612186329285;6175.413335733098;6190.9475389183945;6171.59884607499;6186.505079593621;6187.257529096447;6187.504999600022;6200.441960223601;4728.62171026324;4819.518048195223;4821.614270858393;4851.975740121276;4879.731614761222;3678.576963649161;3706.870259540903;4350.58669426404;4386.780660967002;4408;5645.774169033192;5673.517750991212;5688.914666280009;5700.315962084576;5701.173329867146;5882.117682347682;5910.674912879746;5997.1304160896425;6245.594036387621;6254.186955695731;5586.1062230043;5596.608237423341;5603.80386686464;5603.831885043455;5612.803551875665;5220.947790522122;5235.764390602432;5242.501962313574;5249.527542521115;5255.02781985334;6219.937800621936;6252.030935205051;6263.467605253604;6266.749330026748;6277.811665650038;6056.878862422815;6061.727222275096;6068.726907289094;6068.939310607014;6081.452669259788;6305.1172835803945;6314.747410103544;6332.271788744353;6347.238331400262;6358.650274234777;6044.184035155197;6045.848853778691;6049.364816694184;6071.483923866521;6074.179985701874;6169.218532511505;6187.969060154671;6191.938080619136;6208.099825525183;6212.968935155295;5779.479846813807;5818;6020.487002467517;6066.848328791815;6103.145559621567;5836.445537673914;5903.940960590339;5923.081922301878;5936.643801371932;5972.372900845348;5843.094320380348;5859.882802343844;5862.12068189775;5869.53043756507;5904.3210030847;5849.736761845642;5999.220101386966;6001.549883758648;6015.097735339734;6021.729022193924;5719.599628025998;5777.191193233035;5779.797707080232;5820.272465941757;5829.979753543028 12:33:15 INFO - PROCESS | 1659 | |1;ext.html;476.79736112152324;492.4337012435698;498.71573203274124;515.7137788527367;523.5235935298876;29288.41423171538;38226.426603600936;39274.341503406846;39331.21337573247;39386.015349616275;39560.01099972502;40059.39910901331;40089.59910400895;40123.799381003126;40207.396889046686;8103.270705636492;9355;9530.80938381232;9537.1893389062;9555.18780903624;9413.105754953303;9533.999999999965;9535.523223838814;9537.666181683642;9548.522573871278;9461.385009974365;9535.046495350443;9545.665901693443;9549.952250238774;9564;9502.429854208769;9523.761905952337;9542;9546.952265238664;9548.236141108695;9350.812983740287;9420.057994200593;9435.528223588863;9442.811143777155;9448.811023779555;38628.80685596582;38870;38921.41617875734;38937;38997.22005559901;2816.676082250547;3241.9351612967844;3276.262840860811;3658.359787037258;4013.1973605279063;29409.264768380748;30543.69456305431;30599.235019124477;30702.232444188852;30878.382432351227;3091.119031076155;3113.610798650169;3224.871005159791;3269.6566860479543;3333.449980753173;458571;459728.70135649445;461428;461494;468874;54071.45928540664;54086.45913540814;54507.454925450234;54695.45304546904;54715.63210919679;57634.42365576374;57752;57779.68881244787;57810.13284800731;57858.710706446196;15584.610384740245;15802.288896999433;15810.446634367972;15813.446529371417;15858.207089645472;12312.50749970009;12398.75202495945;12402;12416.130870838955;12434.253944763343;14380.35288412024;15055.473058442902;15065.17141557225;15068.698626027637;15275.403181459178;5801.491612180864;5817.476427121579;6657.500687448461;6796.878515044962;6832.7110418010525;867.3112854544419;875.2166810704384;900.7027680865299;936.1901954809041;936.3164889630608;27807.72192278092;28773.712262877103;28854.134375968755;29590.704092959226;29665.406691866043;824.8432797768405;1031.3605564550028;1042.5100202904775;1047.4082143588848;1075.8440026196158;14639.48761793332;14659.926700366643;14677;15018.849811501963;15064.774028389584;607162;612938;616240;620013;621024;46726;53779.731101344245;53797.73101134547;53805.73097134489;53822.730886345315;1157.438642258504;1251.161721646499;1253.2355263289387;1309.4369421148997;1369.7465968795805;29041;30151;30217.848910755307;30537.847310763304;30764.538531922488;46266.53733462622;46765.76617116893;46799.53200467952;46838.829029274544;46928.7260474627;515443;517208;519935;520744;521297;24360.147394841097;25346.1128860489;25362.873185633955;25905.48189036171;25930;43727;44335.77832110819;44960;45604.315935261664;46700.53299466962;36559.34406559323;36708;36821.55310925328;37774.62225377711;38148.42777358397;35772.64227357797;36899.893003209945;37016.62983370132;37743.81128094342;37930.62069379381;30360.54459183158;36126;36369;37144;37226;31572.526412104296;33015.20287011098;33304.83347583246;33491.1627209312;33551.16122096967;20130.899345503178;22293;24564.87717561401;25165.370865728502;26164.869175654 12:33:15 INFO - PROCESS | 1659 | |2;jquery.html;343.2414364254996;362.7968337730869;366.5125383240291;370.8053272032184;388.8930544100372;14605.488807891712;14877.851221487781;15789.60525986851;19886.90056549715;20384.388468345973;19049.52376190596;19614.117364718586;20038.799612003877;20293.594128117427;20499.692504612405;4814.470408255098;5953.3451320354725;6003.549733769963;6009.57932944694;6013.338532761392;5161.561267292284;5812.709364531777;5818.272715910512;5879.059350503899;5890.587658863881;5594.916076258859;5819.33077696064;5828.7668493260435;5830.708464576775;5838.883222335551;5772.278465191851;5885.5880088393615;5891.705414729289;5893.882122357529;5896.616719913192;4875.975620121877;5051.797928082872;5125.846224613268;5137.075326441239;5149.819756308549;25567.488650226893;25585.36036599081;25627;25987.350316242057;26055.73944260552;2150.8386870984737;2189.693442917993;2243.05791567542;2256.4020534558354;2372.181597348912;15061.548153555412;15647.374105035786;15780.210989450596;15791.763123553155;15908;1733.6099377640055;1770.8583313334902;1801.0634470075618;1877.4836919847014;1903.551409293338;115325;120218;121169.39415302867;121572;122334.38832805867;7078.539894906815;9645.807083858284;10011.299209055296;10065.849012264822;10613.416262105658;12363.876361236273;12459.501619935283;12472.314022728837;12476.563320283747;12483.812742808865;7885.329746971572;8021.598920053974;8083.353331733447;8160.591970401456;8191.83616327682;8354.373421993374;8368.53973031477;8407.41148119638;8534.975802903691;8554.529500877386;3824.330742120118;3881.437191607201;3902.1025164211824;3952.486176796998;3955.129871428281;1916.7987361327139;1938.2925232290395;1939.2049259803696;1943.8833669979847;1976.3478052242726;722.8229083874485;840.9243168114899;868.4745728834091;894.8434024045766;927.7726956895604;20565.981233779137;21372.78627213739;21874.219241877516;21985.010674519686;22441.77558224397;846.8137009857821;925.4308600210957;937.5687183895349;951.8191543606692;991.5587563534158;10937.4531273436;11289.832778250764;11333.886661133449;11384.601538946097;11411.087113030937;574135;574970.50294971;575157.1242143762;584409;585152;77810.83283750636;119453;122229.77770222184;127669;130599;754.864124457592;963.4749061761216;1000.0949141027381;1003.7390278527489;1005.6128390569684;18503.167357468676;19403.4178974631;19752.506187345432;19824.405267841987;19852.503687407352;38614;46549.3017604743;47536.7623161882;52287;52800.47199527956;69855.60288794094;75215.8717619247;80552.79170812326;82429.58785206036;84186.73719894084;6976.720931162697;7796.970953734696;7993.72021979228;8018.318442932183;8345.415820892722;10377;12316.938415308225;12755.48978040905;13256.602301930958;13314.800277995644;8863.911360886308;9796.951015244878;10945.01494865466;11082.445877706245;11165.665030049111;5050.49495050492;9315.86026209621;10465.581376744845;10992.890071099187;11070.003699667068;10827.945860270649;11210.943945280222;11295.943520282675;11423.314601123959;11434.94282528582;7267.255858594005;7291.08861392326;7307.634618268959;7511.436642251962;7568.735094271674;8796.868046979173;8823.823523529622;10173.898261017592;10760.246782725135;10817.51321190564 12:33:15 INFO - PROCESS | 1659 | |3;mootools.html;1341.5371696764612;1494.9551513454596;1496.5884381795006;1675.5392267126538;1830.057520377006;7749.30256276935;8179;8436.987561492622;8710.041895391501;8725.301975841934;8596.3122950164;8649.524276164808;8661.263792577623;8672.43629164104;8677.392582519225;2326.4183954011496;2361.126383238204;2361.49227915998;2361.752016038317;2363.7990770784504;2312.4796920692793;2342.625179971205;2359.846609970355;2360.3391050505884;2361.445060410802;2321.3384185507134;2339.4502291961376;2354.423166324253;2356.469794296278;2367.6803631509783;2283.5318759654397;2318.48993221491;2323.5701395241936;2328.2549584133167;2343.086196383412;2304.055337311694;2307.361773141072;2309.145616122029;2312.3294244669028;2312.4450131968374;8607.010193827664;8640.438371505832;8709.216170544618;8717.389782715212;8722.08418116095;5064.594832413398;5083.79664813407;5235.712035838027;5240.266362709223;5246.947530524722;3534.5228394166716;3632.146445585285;3632.6367363263726;3636.072801435617;3655.3968595181764;1059.5549869055014;1063.5533076108054;1073.3452593917705;1082.6048492300333;1100.8128618134856;11537.423128843524;11541.538338466451;11617.535298588047;11620.128490363257;11638.825417618742;4400.3399490076745;4568.771561421916;4617.584417402382;4811.037792441526;4848.684835485682;8952.373333866697;9026.232770214605;9026.729198124067;9027.45835249874;9030.45817250967;5194.246834209019;5196.506331898464;5197.922031169535;5201.6358854880555;5204.375474943031;4072.3687828386646;4357.629601483846;4376.934345984812;4495.910081798411;4872.439669437989;3620.402633565485;3657.634236576321;3709.1283548366114;3746.756460830092;3750.287445385368;1138.0895283772948;1141.4749215360862;1142.7257458210136;1152.3489228585822;1157.1841851494676;828.8010877389383;847.7329641163014;877.3113106211594;900.401233179928;915.7893684452613;5660.660360378391;5701.258836351248;5971.731272092767;5989.610675306091;6009.939900601025;803.6062329458523;877.464437234839;902.3503077783985;914.3416739947228;934.2759361494896;3836.3478208704587;3844.9231015379537;3851.247682304601;3894.299026175259;3950.802459876995;9238.353315267866;10411.47942602882;10627.14982801374;10803.513841877135;10814.134869210602;10653.946730266454;10739.785204295871;10740.838887416696;10749.62376316825;10756.247062705535;6764.526483146231;7655.540667559852;8172.060213075453;8175.427720059539;8183.83632327362;5786.884262314646;6052.122442245938;6070.848228794315;6117.510599152146;6125.1424800527075;9378.765530861783;9711.465869377253;9979.900200997898;10015.298929074896;10073.647422340184;9992.050755178247;10151.64469243573;10157.847632285373;10263.84604230952;10328.277020608486;7405.740799072007;7440.925590744023;7494.400447964041;7551.962240188764;7640.121386040676;8067.87898181516;8136.552489612891;8147.633356498854;8158.265756081981;8158.592070396575;7201.6399180041835;7372.225916278713;7430.96284518574;7478.925210748041;7480.476366654282;7179.748708795167;7223.575450225603;7250.274972502708;7316.304951029643;7326.560406375635;6804.353586409283;6964.408025317702;6968.128983877015;6971.930280697331;7001.50989430735;4940.851774446773;4982.576480999156;5016.272640467185;5033.597312215087;5106.183010718267;4918.458969513422;4969.1800852860315;5032.043911656773;5184.274201611853;5201.375834899836 12:33:15 INFO - PROCESS | 1659 | |4;prototype.html;294.3069072334652;302.5159744408946;305.1166871905832;310.077519379845;313.48588315163124;4374.540673229313;5243.8951220975605;5280.551153151977;5296.761645725942;5318.76065577049;4994.677474974136;5193.610479214055;5247.396549396821;5259.631825772198;5280.287161233232;3450.9482357764655;3650.6166852480374;3739.233457141293;3930.8231129599235;4050.3721923101966;3399.8980030598996;3697.1681371691425;4034.2334956358345;4047.089404883907;4050.6961977851633;3305.3058857639803;3308.4541050726602;3321.2195134143576;3595.7123430125657;4009.9198016039663;3552.982235088808;3781.5840257571645;3787.6780473659774;3850.691944644436;4046.2514434829504;3090.3664748726455;3092.38152369527;3223.0169798211537;3397.575303087114;3527.276908233806;4713.457952335457;5145.253938178943;5184.818531351422;5199.896002079975;5228.712420816853;4447.355133505656;4620.976895115503;4625.722456652612;4645.628349732014;4648.558386953234;5130.589552835801;5450.836474905759;5463.043967305705;5517.682573319215;5547.972260138714;3317.6397676952574;3875.9806200969097;3893.143508428141;3903.4144878268203;3937.330653788863;5618.887622247573;5817.301923769132;5846.444587764156;5879.823605291848;5886.529077673818;3899.512560929884;3919.490466239428;3925.666318362971;3928.3125453045604;3967.5834037425657;3338.8998330050135;3401.863925443004;3964.20715856824;3975.6620687241907;3976.681865450757;4554.407926969473;4585.93632865212;4604.112313951264;4630.374899388539;4643.257078867365;5314.601404894571;5428.240046393508;5520.613557051048;5542.61201715876;5604.663720176803;1332.6468485851258;1347.8517363090052;1354.9066691960943;1358.1443690475135;1362.9777666749937;666.8799616069155;778.5678948183812;793.8608097380278;829.1708291708292;835.5738573327525;3909.92180156401;3960.108975480494;4036.515618125785;4374.868753937323;5083.364579427572;4320.049589090395;4700.38070685577;4734.857954261378;5674.631148975303;5680.636647204641;5165.468920229136;5171.974140129275;5190.987757387274;5195.844124676266;5203.453637368098;2498.7880877774264;2644.405008873027;2719.6872359678496;2772.556390977434;2895.247235718686;4689.788959496763;5361.212169671749;5863.970680146572;5883.588148829541;5887.264091988502;4486.75322857246;5292.935755055531;5771.538276937918;5771.62360019385;5786.594938354359;5922.851928701731;5968.492678122321;5976.641401515923;5983.700814959322;6013.548983826143;5729.4843464088435;5735.340435849931;5753.820466804379;5755.625884317506;5759.683217423082;4710.4818469967595;5312.979234623244;5577.462254674195;5600.467955544217;5660.745266462936;4752.500987396274;5569.458388903067;5901.203337549376;5929.792457264148;5944.613600115978;2913.4464451754097;3665.725070619661;3859.536855577349;3873.9806300968316;3881.3013657541915;4381.802818873095;4947.773501924221;5170.37955445334;5787.623804452697;5792.652440853562;5518.806841760519;5537.723113844211;5550.306211723535;5558.305211848519;5581.358143813513;5287.550558202442;5548.917960997566;5556.777728890961;5562.2490963721;5579.16312553104;4367.694261401672;5448.4006759257245;5461.344638643388;5483.026110600101;5486.286782718221;5240.7641656126325;5436;5568.331800184003;5569.860753481195;5595.300587426572;4270.167317373082;4471.955280447154;4916.631252656137;5162.535371816554;5213.947860521498 12:33:15 INFO - PROCESS | 1659 | |5;yui.html;606.3713800674078;728.1553398058253;755.8639444899916;777.5412506621093;814.4909431605247;12507.624771256877;12601.810972835416;12670.366481675914;15959.601009974756;15973.760393594106;14913.627159321022;14990.175540345277;15326.770098448504;15370.385184592631;15426.537203883901;822.3626689315769;850.1328644782318;852.0840096895831;853.1127627267634;853.8975322961253;831.4969443486694;832.4797001873828;833.5623797506297;834.3992325525642;835.1648351648352;884.9469031858077;887.8845750052484;888.0630934364275;888.2627419242046;889.1952782731606;840.3361344537808;872.3326655108815;881.4446898453965;884.6549845560237;884.8274586455643;647.4431988489895;649.5810202419412;650.3171669746795;650.8633187030729;651.3681728723132;11809.173357864953;14216.075955062888;14271.928640356835;14280.500182493666;14284.357203925849;11093;13510.662233444144;13529.59411217665;13936.58190254294;14050.578482645536;11698;11722.941385293105;11784.175107742376;11868.643940681794;11966.691476554659;3672.866079738019;3710.424884142962;3768.7550309229814;3780.2250538639514;3826.4642949987024;109757.45121274423;111212;113659.9481141492;122355.77644223622;122913;10619.150467962545;10778.137748980063;10789.83815242772;10965.122790176767;11087.556497740161;21318.360449186548;21473.570528589655;21692.34922952314;21712.89143554304;21738.13047478083;7679.692812287558;7698.307152356315;7769.805754856061;7826.921730782733;7980.600969951595;4316.827326906889;4342.500612429548;4377.365282034151;4400.141972315432;4512.34570987205;3493.7379696522353;3644.0707619557375;3645.289168612095;3646.3072016316814;3647.4893514907935;544.5480251391335;659.5053709717712;666.2105405094978;668.3350066683681;668.3984414027415;575.6603603873651;620.652434636605;649.5810202419412;662.2185820731487;678.575890068707;8952.641894324284;9128.634854605742;9251.167394934488;9257.629694812267;9265.861012084824;626.1984659635674;652.6164531193899;658.4962503684685;668.6389349751178;672.4519516594058;4304.849330273425;4547.726636541774;4548.249538826123;4615.14619795339;4646.86059418218;99800;109163.45418273017;126820;145887.94168027793;163773;139366;140691;140859;140934;141941;118278.40860795641;118560.40719796518;120216.3989180066;120255;120664;7571.772846814604;8684.826303474021;8715.869261960948;8787.868181977148;8857.535173429476;41105.972350691474;54409.10318071392;62668;65579.03262902121;67425.3257467419;75641;94569;97125.02874971443;101818;102508.48745756224;11081.002709756161;11223.326600404002;11496.770064598828;11498.655040348804;11735.931636118623;12282.877171228174;12478.625641230778;12646;12664.587406048027;12748.936255318664;11865.703357416134;11879.406029698654;11938.940305298418;12046.638600841989;12269.57056503018;11601.303921764722;11846.407679615812;12184.512619495476;12192.08559358034;12344.876551234733;11616.5353385867;11687.357195353998;11769;11933.701657458632;12093.153479256367;9698.078682525149;10307.226957978331;10364.637237696947;10447.94776026115;10531.736706582396;9474.289428292768;10123.443210623487;10138.442385668859;10145.188384929335;10148.32552628808 12:33:15 INFO - PROCESS | 1659 | __end_tp_report 12:33:15 INFO - PROCESS | 1659 | __start_cc_report 12:33:15 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1800 12:33:15 INFO - PROCESS | 1659 | __end_cc_report 12:33:15 INFO - PROCESS | 1659 | __startTimestamp1476387195296__endTimestamp 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | ------- Summary: start ------- 12:33:15 INFO - PROCESS | 1659 | Number of tests: 6 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5644.69 Median:5836.45 stddev:668.00 (11.4%) stddev-sans-first:635.89 12:33:15 INFO - PROCESS | 1659 | Values: 2956.5 3314.3 3322.7 3350.5 3381.5 5479.1 5525.0 5544.1 5623.0 5632.4 4617.5 5542.0 5565.2 5578.6 5580.1 5032.0 5073.2 5085.0 5118.9 5180.0 5090.2 5176.1 5181.1 5182.6 5184.4 5079.9 5115.5 5123.7 5127.2 5154.4 5208.6 5211.4 5211.9 5218.1 5269.3 5017.9 5021.1 5065.6 5119.1 5132.9 5490.3 5570.2 5597.1 5605.0 5640.6 6162.8 6177.8 6181.0 6183.9 6192.9 6030.4 6122.6 6185.3 6242.7 6258.3 5660.2 5712.4 5713.1 5728.7 5757.9 6292.2 6307.1 6309.5 6330.7 6332.7 5894.2 5979.7 6003.7 6039.9 6087.4 6106.8 6138.4 6183.4 6183.8 6224.4 6152.4 6159.4 6159.7 6175.4 6190.9 6171.6 6186.5 6187.3 6187.5 6200.4 4728.6 4819.5 4821.6 4852.0 4879.7 3678.6 3706.9 4350.6 4386.8 4408.0 5645.8 5673.5 5688.9 5700.3 5701.2 5882.1 5910.7 5997.1 6245.6 6254.2 5586.1 5596.6 5603.8 5603.8 5612.8 5220.9 5235.8 5242.5 5249.5 5255.0 6219.9 6252.0 6263.5 6266.7 6277.8 6056.9 6061.7 6068.7 6068.9 6081.5 6305.1 6314.7 6332.3 6347.2 6358.7 6044.2 6045.8 6049.4 6071.5 6074.2 6169.2 6188.0 6191.9 6208.1 6213.0 5779.5 5818.0 6020.5 6066.8 6103.1 5836.4 5903.9 5923.1 5936.6 5972.4 5843.1 5859.9 5862.1 5869.5 5904.3 5849.7 5999.2 6001.5 6015.1 6021.7 5719.6 5777.2 5779.8 5820.3 5830.0 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:65884.47 Median:39302.78 stddev:143134.51 (364.2%) stddev-sans-first:143451.80 12:33:15 INFO - PROCESS | 1659 | Values: 476.8 492.4 498.7 515.7 523.5 29288.4 38226.4 39274.3 39331.2 39386.0 39560.0 40059.4 40089.6 40123.8 40207.4 8103.3 9355.0 9530.8 9537.2 9555.2 9413.1 9534.0 9535.5 9537.7 9548.5 9461.4 9535.0 9545.7 9550.0 9564.0 9502.4 9523.8 9542.0 9547.0 9548.2 9350.8 9420.1 9435.5 9442.8 9448.8 38628.8 38870.0 38921.4 38937.0 38997.2 2816.7 3241.9 3276.3 3658.4 4013.2 29409.3 30543.7 30599.2 30702.2 30878.4 3091.1 3113.6 3224.9 3269.7 3333.4 458571.0 459728.7 461428.0 461494.0 468874.0 54071.5 54086.5 54507.5 54695.5 54715.6 57634.4 57752.0 57779.7 57810.1 57858.7 15584.6 15802.3 15810.4 15813.4 15858.2 12312.5 12398.8 12402.0 12416.1 12434.3 14380.4 15055.5 15065.2 15068.7 15275.4 5801.5 5817.5 6657.5 6796.9 6832.7 867.3 875.2 900.7 936.2 936.3 27807.7 28773.7 28854.1 29590.7 29665.4 824.8 1031.4 1042.5 1047.4 1075.8 14639.5 14659.9 14677.0 15018.8 15064.8 607162.0 612938.0 616240.0 620013.0 621024.0 46726.0 53779.7 53797.7 53805.7 53822.7 1157.4 1251.2 1253.2 1309.4 1369.7 29041.0 30151.0 30217.8 30537.8 30764.5 46266.5 46765.8 46799.5 46838.8 46928.7 515443.0 517208.0 519935.0 520744.0 521297.0 24360.1 25346.1 25362.9 25905.5 25930.0 43727.0 44335.8 44960.0 45604.3 46700.5 36559.3 36708.0 36821.6 37774.6 38148.4 35772.6 36899.9 37016.6 37743.8 37930.6 30360.5 36126.0 36369.0 37144.0 37226.0 31572.5 33015.2 33304.8 33491.2 33551.2 20130.9 22293.0 24564.9 25165.4 26164.9 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33871.16 Median:364.65 stddev:96614.45 (26494.8%) stddev-sans-first:96852.68 12:33:15 INFO - PROCESS | 1659 | Values: 343.2 362.8 366.5 370.8 388.9 14605.5 14877.9 15789.6 19886.9 20384.4 19049.5 19614.1 20038.8 20293.6 20499.7 4814.5 5953.3 6003.5 6009.6 6013.3 5161.6 5812.7 5818.3 5879.1 5890.6 5594.9 5819.3 5828.8 5830.7 5838.9 5772.3 5885.6 5891.7 5893.9 5896.6 4876.0 5051.8 5125.8 5137.1 5149.8 25567.5 25585.4 25627.0 25987.4 26055.7 2150.8 2189.7 2243.1 2256.4 2372.2 15061.5 15647.4 15780.2 15791.8 15908.0 1733.6 1770.9 1801.1 1877.5 1903.6 115325.0 120218.0 121169.4 121572.0 122334.4 7078.5 9645.8 10011.3 10065.8 10613.4 12363.9 12459.5 12472.3 12476.6 12483.8 7885.3 8021.6 8083.4 8160.6 8191.8 8354.4 8368.5 8407.4 8535.0 8554.5 3824.3 3881.4 3902.1 3952.5 3955.1 1916.8 1938.3 1939.2 1943.9 1976.3 722.8 840.9 868.5 894.8 927.8 20566.0 21372.8 21874.2 21985.0 22441.8 846.8 925.4 937.6 951.8 991.6 10937.5 11289.8 11333.9 11384.6 11411.1 574135.0 574970.5 575157.1 584409.0 585152.0 77810.8 119453.0 122229.8 127669.0 130599.0 754.9 963.5 1000.1 1003.7 1005.6 18503.2 19403.4 19752.5 19824.4 19852.5 38614.0 46549.3 47536.8 52287.0 52800.5 69855.6 75215.9 80552.8 82429.6 84186.7 6976.7 7797.0 7993.7 8018.3 8345.4 10377.0 12316.9 12755.5 13256.6 13314.8 8863.9 9797.0 10945.0 11082.4 11165.7 5050.5 9315.9 10465.6 10992.9 11070.0 10827.9 11210.9 11295.9 11423.3 11434.9 7267.3 7291.1 7307.6 7511.4 7568.7 8796.9 8823.8 10173.9 10760.2 10817.5 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5619.12 Median:5032.82 stddev:3144.86 (62.5%) stddev-sans-first:3137.25 12:33:15 INFO - PROCESS | 1659 | Values: 1341.5 1495.0 1496.6 1675.5 1830.1 7749.3 8179.0 8437.0 8710.0 8725.3 8596.3 8649.5 8661.3 8672.4 8677.4 2326.4 2361.1 2361.5 2361.8 2363.8 2312.5 2342.6 2359.8 2360.3 2361.4 2321.3 2339.5 2354.4 2356.5 2367.7 2283.5 2318.5 2323.6 2328.3 2343.1 2304.1 2307.4 2309.1 2312.3 2312.4 8607.0 8640.4 8709.2 8717.4 8722.1 5064.6 5083.8 5235.7 5240.3 5246.9 3534.5 3632.1 3632.6 3636.1 3655.4 1059.6 1063.6 1073.3 1082.6 1100.8 11537.4 11541.5 11617.5 11620.1 11638.8 4400.3 4568.8 4617.6 4811.0 4848.7 8952.4 9026.2 9026.7 9027.5 9030.5 5194.2 5196.5 5197.9 5201.6 5204.4 4072.4 4357.6 4376.9 4495.9 4872.4 3620.4 3657.6 3709.1 3746.8 3750.3 1138.1 1141.5 1142.7 1152.3 1157.2 828.8 847.7 877.3 900.4 915.8 5660.7 5701.3 5971.7 5989.6 6009.9 803.6 877.5 902.4 914.3 934.3 3836.3 3844.9 3851.2 3894.3 3950.8 9238.4 10411.5 10627.1 10803.5 10814.1 10653.9 10739.8 10740.8 10749.6 10756.2 6764.5 7655.5 8172.1 8175.4 8183.8 5786.9 6052.1 6070.8 6117.5 6125.1 9378.8 9711.5 9979.9 10015.3 10073.6 9992.1 10151.6 10157.8 10263.8 10328.3 7405.7 7440.9 7494.4 7552.0 7640.1 8067.9 8136.6 8147.6 8158.3 8158.6 7201.6 7372.2 7431.0 7478.9 7480.5 7179.7 7223.6 7250.3 7316.3 7326.6 6804.4 6964.4 6968.1 6971.9 7001.5 4940.9 4982.6 5016.3 5033.6 5106.2 4918.5 4969.2 5032.0 5184.3 5201.4 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4435.71 Median:5106.98 stddev:1405.59 (27.5%) stddev-sans-first:1374.73 12:33:15 INFO - PROCESS | 1659 | Values: 294.3 302.5 305.1 310.1 313.5 4374.5 5243.9 5280.6 5296.8 5318.8 4994.7 5193.6 5247.4 5259.6 5280.3 3450.9 3650.6 3739.2 3930.8 4050.4 3399.9 3697.2 4034.2 4047.1 4050.7 3305.3 3308.5 3321.2 3595.7 4009.9 3553.0 3781.6 3787.7 3850.7 4046.3 3090.4 3092.4 3223.0 3397.6 3527.3 4713.5 5145.3 5184.8 5199.9 5228.7 4447.4 4621.0 4625.7 4645.6 4648.6 5130.6 5450.8 5463.0 5517.7 5548.0 3317.6 3876.0 3893.1 3903.4 3937.3 5618.9 5817.3 5846.4 5879.8 5886.5 3899.5 3919.5 3925.7 3928.3 3967.6 3338.9 3401.9 3964.2 3975.7 3976.7 4554.4 4585.9 4604.1 4630.4 4643.3 5314.6 5428.2 5520.6 5542.6 5604.7 1332.6 1347.9 1354.9 1358.1 1363.0 666.9 778.6 793.9 829.2 835.6 3909.9 3960.1 4036.5 4374.9 5083.4 4320.0 4700.4 4734.9 5674.6 5680.6 5165.5 5172.0 5191.0 5195.8 5203.5 2498.8 2644.4 2719.7 2772.6 2895.2 4689.8 5361.2 5864.0 5883.6 5887.3 4486.8 5292.9 5771.5 5771.6 5786.6 5922.9 5968.5 5976.6 5983.7 6013.5 5729.5 5735.3 5753.8 5755.6 5759.7 4710.5 5313.0 5577.5 5600.5 5660.7 4752.5 5569.5 5901.2 5929.8 5944.6 2913.4 3665.7 3859.5 3874.0 3881.3 4381.8 4947.8 5170.4 5787.6 5792.7 5518.8 5537.7 5550.3 5558.3 5581.4 5287.6 5548.9 5556.8 5562.2 5579.2 4367.7 5448.4 5461.3 5483.0 5486.3 5240.8 5436.0 5568.3 5569.9 5595.3 4270.2 4472.0 4916.6 5162.5 5213.9 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24639.35 Median:3644.68 stddev:40308.63 (1106.0%) stddev-sans-first:40381.32 12:33:15 INFO - PROCESS | 1659 | Values: 606.4 728.2 755.9 777.5 814.5 12507.6 12601.8 12670.4 15959.6 15973.8 14913.6 14990.2 15326.8 15370.4 15426.5 822.4 850.1 852.1 853.1 853.9 831.5 832.5 833.6 834.4 835.2 884.9 887.9 888.1 888.3 889.2 840.3 872.3 881.4 884.7 884.8 647.4 649.6 650.3 650.9 651.4 11809.2 14216.1 14271.9 14280.5 14284.4 11093.0 13510.7 13529.6 13936.6 14050.6 11698.0 11722.9 11784.2 11868.6 11966.7 3672.9 3710.4 3768.8 3780.2 3826.5 109757.5 111212.0 113659.9 122355.8 122913.0 10619.2 10778.1 10789.8 10965.1 11087.6 21318.4 21473.6 21692.3 21712.9 21738.1 7679.7 7698.3 7769.8 7826.9 7980.6 4316.8 4342.5 4377.4 4400.1 4512.3 3493.7 3644.1 3645.3 3646.3 3647.5 544.5 659.5 666.2 668.3 668.4 575.7 620.7 649.6 662.2 678.6 8952.6 9128.6 9251.2 9257.6 9265.9 626.2 652.6 658.5 668.6 672.5 4304.8 4547.7 4548.2 4615.1 4646.9 99800.0 109163.5 126820.0 145887.9 163773.0 139366.0 140691.0 140859.0 140934.0 141941.0 118278.4 118560.4 120216.4 120255.0 120664.0 7571.8 8684.8 8715.9 8787.9 8857.5 41106.0 54409.1 62668.0 65579.0 67425.3 75641.0 94569.0 97125.0 101818.0 102508.5 11081.0 11223.3 11496.8 11498.7 11735.9 12282.9 12478.6 12646.0 12664.6 12748.9 11865.7 11879.4 11938.9 12046.6 12269.6 11601.3 11846.4 12184.5 12192.1 12344.9 11616.5 11687.4 11769.0 11933.7 12093.2 9698.1 10307.2 10364.6 10447.9 10531.7 9474.3 10123.4 10138.4 10145.2 10148.3 12:33:15 INFO - PROCESS | 1659 | -------- Summary: end -------- 12:33:15 INFO - PROCESS | 1659 | 12:33:15 INFO - TEST-INFO | 1659: exit 0 12:33:15 INFO - TEST-OK | dromaeo_css | took 1118904ms 12:33:15 INFO - TEST-START | kraken 12:33:15 INFO - operating with platform_type : mac_ 12:33:15 INFO - Initialising browser for kraken test... 12:33:15 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpTUX_EP/profile http://localhost:49274/getInfo.html) 12:33:18 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 12:33:18 INFO - PROCESS | 1666 | colorDepth:24 12:33:18 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 12:33:18 INFO - PROCESS | 1666 | __metrics 12:33:18 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:33:18 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:19 INFO - TEST-INFO | 1666: exit 0 12:33:19 INFO - Browser initialized. 12:33:19 INFO - Running cycle 1/1 for kraken test... 12:33:19 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpTUX_EP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:31 INFO - PROCESS | 1667 | RSS: Main: 237170688 12:33:31 INFO - PROCESS | 1667 | 12:33:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:35 INFO - PROCESS | 1667 | RSS: Main: 294514688 12:33:35 INFO - PROCESS | 1667 | 12:33:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 12:33:38 INFO - PROCESS | 1667 | RSS: Main: 296636416 12:33:38 INFO - PROCESS | 1667 | 12:33:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 12:33:42 INFO - PROCESS | 1667 | RSS: Main: 388022272 12:33:42 INFO - PROCESS | 1667 | 12:33:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 12:33:45 INFO - PROCESS | 1667 | RSS: Main: 289775616 12:33:45 INFO - PROCESS | 1667 | 12:33:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:47 INFO - PROCESS | 1667 | RSS: Main: 292929536 12:33:47 INFO - PROCESS | 1667 | 12:33:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:51 INFO - PROCESS | 1667 | RSS: Main: 329216000 12:33:51 INFO - PROCESS | 1667 | 12:33:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 12:33:54 INFO - PROCESS | 1667 | RSS: Main: 329777152 12:33:54 INFO - PROCESS | 1667 | 12:33:57 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 12:33:58 INFO - PROCESS | 1667 | RSS: Main: 328077312 12:33:58 INFO - PROCESS | 1667 | 12:33:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:00 INFO - PROCESS | 1667 | RSS: Main: 301629440 12:34:00 INFO - PROCESS | 1667 | 12:34:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:03 INFO - PROCESS | 1667 | RSS: Main: 307073024 12:34:03 INFO - PROCESS | 1667 | 12:34:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:05 INFO - PROCESS | 1667 | RSS: Main: 313851904 12:34:05 INFO - PROCESS | 1667 | 12:34:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:09 INFO - PROCESS | 1667 | RSS: Main: 322699264 12:34:09 INFO - PROCESS | 1667 | 12:34:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:13 INFO - PROCESS | 1667 | RSS: Main: 322101248 12:34:13 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 12:34:15 INFO - PROCESS | 1667 | RSS: Main: 366030848 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | __start_tp_report 12:34:15 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 12:34:15 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 12:34:15 INFO - PROCESS | 1667 | |i|pagename|runs| 12:34:15 INFO - PROCESS | 1667 | |0;ai-astar;113;114;115;117;163;171;113;112;114;112 12:34:15 INFO - PROCESS | 1667 | |1;audio-beat-detection;148;115;111;148;113;106;96;134;176;142 12:34:15 INFO - PROCESS | 1667 | |2;audio-dft;182;186;184;184;182;185;183;218;183;184 12:34:15 INFO - PROCESS | 1667 | |3;audio-fft;82;59;59;90;76;86;81;81;84;59 12:34:15 INFO - PROCESS | 1667 | |4;audio-oscillator;90;69;96;70;68;69;92;70;96;126 12:34:15 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;91;87;90;87;90;87;91;87;89;86 12:34:15 INFO - PROCESS | 1667 | |6;imaging-darkroom;94;95;96;94;95;95;95;95;95;96 12:34:15 INFO - PROCESS | 1667 | |7;imaging-desaturate;86;87;85;87;83;85;71;136;134;103 12:34:15 INFO - PROCESS | 1667 | |8;json-parse-financial;56;53;54;53;54;56;55;54;54;56 12:34:15 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;64;74;75;103;107;101;73;71;69;69 12:34:15 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;87;85;86;90;90;86;85;88;92;85 12:34:15 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;148;156;134;148;153;152;135;146;179;148 12:34:15 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;170;171;171;168;173;170;230;171;180;235 12:34:15 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;68;69;69;69;69;69;67;69;69;69 12:34:15 INFO - PROCESS | 1667 | __end_tp_report 12:34:15 INFO - PROCESS | 1667 | __start_cc_report 12:34:15 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2028 12:34:15 INFO - PROCESS | 1667 | __end_cc_report 12:34:15 INFO - PROCESS | 1667 | __startTimestamp1476387255281__endTimestamp 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | ------- Summary: start ------- 12:34:15 INFO - PROCESS | 1667 | Number of tests: 14 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:124.40 Median:114.50 stddev:22.58 (19.7%) stddev-sans-first:23.57 12:34:15 INFO - PROCESS | 1667 | Values: 113.0 114.0 115.0 117.0 163.0 171.0 113.0 112.0 114.0 112.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:145.00 stddev:24.76 (17.1%) stddev-sans-first:25.28 12:34:15 INFO - PROCESS | 1667 | Values: 148.0 115.0 111.0 148.0 113.0 106.0 96.0 134.0 176.0 142.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:187.10 Median:184.00 stddev:10.93 (5.9%) stddev-sans-first:11.43 12:34:15 INFO - PROCESS | 1667 | Values: 182.0 186.0 184.0 184.0 182.0 185.0 183.0 218.0 183.0 184.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:75.70 Median:81.50 stddev:12.07 (14.8%) stddev-sans-first:12.59 12:34:15 INFO - PROCESS | 1667 | Values: 82.0 59.0 59.0 90.0 76.0 86.0 81.0 81.0 84.0 59.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:80.00 stddev:18.99 (23.7%) stddev-sans-first:20.04 12:34:15 INFO - PROCESS | 1667 | Values: 90.0 69.0 96.0 70.0 68.0 69.0 92.0 70.0 96.0 126.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:1.90 (2.1%) stddev-sans-first:1.79 12:34:15 INFO - PROCESS | 1667 | Values: 91.0 87.0 90.0 87.0 90.0 87.0 91.0 87.0 89.0 86.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 12:34:15 INFO - PROCESS | 1667 | Values: 94.0 95.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 96.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:85.00 stddev:22.09 (26.0%) stddev-sans-first:23.15 12:34:15 INFO - PROCESS | 1667 | Values: 86.0 87.0 85.0 87.0 83.0 85.0 71.0 136.0 134.0 103.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.18 (2.2%) stddev-sans-first:1.12 12:34:15 INFO - PROCESS | 1667 | Values: 56.0 53.0 54.0 53.0 54.0 56.0 55.0 54.0 54.0 56.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:80.60 Median:70.00 stddev:16.28 (23.3%) stddev-sans-first:16.12 12:34:15 INFO - PROCESS | 1667 | Values: 64.0 74.0 75.0 103.0 107.0 101.0 73.0 71.0 69.0 69.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.50 stddev:2.50 (2.9%) stddev-sans-first:2.65 12:34:15 INFO - PROCESS | 1667 | Values: 87.0 85.0 86.0 90.0 90.0 86.0 85.0 88.0 92.0 85.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:150.00 stddev:12.47 (8.3%) stddev-sans-first:13.20 12:34:15 INFO - PROCESS | 1667 | Values: 148.0 156.0 134.0 148.0 153.0 152.0 135.0 146.0 179.0 148.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.90 Median:172.00 stddev:25.84 (15.0%) stddev-sans-first:26.91 12:34:15 INFO - PROCESS | 1667 | Values: 170.0 171.0 171.0 168.0 173.0 170.0 230.0 171.0 180.0 235.0 12:34:15 INFO - PROCESS | 1667 | 12:34:15 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:34:15 INFO - PROCESS | 1667 | Values: 68.0 69.0 69.0 69.0 69.0 69.0 67.0 69.0 69.0 69.0 12:34:15 INFO - PROCESS | 1667 | -------- Summary: end -------- 12:34:15 INFO - PROCESS | 1667 | 12:34:16 INFO - TEST-INFO | 1667: exit 0 12:34:16 INFO - TEST-OK | kraken | took 60324ms 12:34:16 INFO - SUITE-END | took 1179s 12:34:16 INFO - Completed test suite (00:19:39) 12:34:16 INFO - javascript benchmark 12:34:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2956.512175491044, 3314.254292784123, 3322.7009569138786, 3350.5476760637307, 3381.492776083587, 5479.0959491683925, 5525.033119204143, 5544.112941929287, 5622.987862184805, 5632.408597097307, 4617.468991066028, 5542.0, 5565.2486914266565, 5578.609497335178, 5580.107182850745, 5032.0, 5073.239014147889, 5085.033843569729, 5118.923216151761, 5180.041692286939, 5090.211017292307, 5176.145935920568, 5181.1192097343355, 5182.611304152185, 5184.377874655047, 5079.923801142986, 5115.514026167508, 5123.666961647499, 5127.2052831811125, 5154.355705536808, 5208.583313334924, 5211.374635043819, 5211.895762084775, 5218.0607490651855, 5269.262303277545, 5017.89964200714, 5021.121303771825, 5065.645404821664, 5119.104156772587, 5132.948670513284, 5490.313710786152, 5570.220169176318, 5597.132444471114, 5605.0, 5640.605157638967, 6162.7534898604, 6177.845553861144, 6181.041938499539, 6183.907241391383, 6192.8761424771255, 6030.427109424598, 6122.602030867979, 6185.319614842364, 6242.719077641517, 6258.280297765769, 5660.2075709400715, 5712.371639119693, 5713.143028545752, 5728.656280623176, 5757.8560535986935, 6292.244930608356, 6307.117003619497, 6309.495240380758, 6330.746770129143, 6332.746690132343, 5894.17481552583, 5979.730912108966, 6003.669798161143, 6039.857076649423, 6087.421694938974, 6106.786262480792, 6138.386161383826, 6183.443490085914, 6183.783567575113, 6224.408681175281, 6152.415520525543, 6159.353267906895, 6159.6612186329285, 6175.413335733098, 6190.9475389183945, 6171.59884607499, 6186.505079593621, 6187.257529096447, 6187.504999600022, 6200.441960223601, 4728.62171026324, 4819.518048195223, 4821.614270858393, 4851.975740121276, 4879.731614761222, 3678.576963649161, 3706.870259540903, 4350.58669426404, 4386.780660967002, 4408.0, 5645.774169033192, 5673.517750991212, 5688.914666280009, 5700.315962084576, 5701.173329867146, 5882.117682347682, 5910.674912879746, 5997.1304160896425, 6245.594036387621, 6254.186955695731, 5586.1062230043, 5596.608237423341, 5603.80386686464, 5603.831885043455, 5612.803551875665, 5220.947790522122, 5235.764390602432, 5242.501962313574, 5249.527542521115, 5255.02781985334, 6219.937800621936, 6252.030935205051, 6263.467605253604, 6266.749330026748, 6277.811665650038, 6056.878862422815, 6061.727222275096, 6068.726907289094, 6068.939310607014, 6081.452669259788, 6305.1172835803945, 6314.747410103544, 6332.271788744353, 6347.238331400262, 6358.650274234777, 6044.184035155197, 6045.848853778691, 6049.364816694184, 6071.483923866521, 6074.179985701874, 6169.218532511505, 6187.969060154671, 6191.938080619136, 6208.099825525183, 6212.968935155295, 5779.479846813807, 5818.0, 6020.487002467517, 6066.848328791815, 6103.145559621567, 5836.445537673914, 5903.940960590339, 5923.081922301878, 5936.643801371932, 5972.372900845348, 5843.094320380348, 5859.882802343844, 5862.12068189775, 5869.53043756507, 5904.3210030847, 5849.736761845642, 5999.220101386966, 6001.549883758648, 6015.097735339734, 6021.729022193924, 5719.599628025998, 5777.191193233035, 5779.797707080232, 5820.272465941757, 5829.979753543028], "value": 5598.966565677851, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.79736112152324, 492.4337012435698, 498.71573203274124, 515.7137788527367, 523.5235935298876, 29288.41423171538, 38226.426603600936, 39274.341503406846, 39331.21337573247, 39386.015349616275, 39560.01099972502, 40059.39910901331, 40089.59910400895, 40123.799381003126, 40207.396889046686, 8103.270705636492, 9355.0, 9530.80938381232, 9537.1893389062, 9555.18780903624, 9413.105754953303, 9533.999999999965, 9535.523223838814, 9537.666181683642, 9548.522573871278, 9461.385009974365, 9535.046495350443, 9545.665901693443, 9549.952250238774, 9564.0, 9502.429854208769, 9523.761905952337, 9542.0, 9546.952265238664, 9548.236141108695, 9350.812983740287, 9420.057994200593, 9435.528223588863, 9442.811143777155, 9448.811023779555, 38628.80685596582, 38870.0, 38921.41617875734, 38937.0, 38997.22005559901, 2816.676082250547, 3241.9351612967844, 3276.262840860811, 3658.359787037258, 4013.1973605279063, 29409.264768380748, 30543.69456305431, 30599.235019124477, 30702.232444188852, 30878.382432351227, 3091.119031076155, 3113.610798650169, 3224.871005159791, 3269.6566860479543, 3333.449980753173, 458571.0, 459728.70135649445, 461428.0, 461494.0, 468874.0, 54071.45928540664, 54086.45913540814, 54507.454925450234, 54695.45304546904, 54715.63210919679, 57634.42365576374, 57752.0, 57779.68881244787, 57810.13284800731, 57858.710706446196, 15584.610384740245, 15802.288896999433, 15810.446634367972, 15813.446529371417, 15858.207089645472, 12312.50749970009, 12398.75202495945, 12402.0, 12416.130870838955, 12434.253944763343, 14380.35288412024, 15055.473058442902, 15065.17141557225, 15068.698626027637, 15275.403181459178, 5801.491612180864, 5817.476427121579, 6657.500687448461, 6796.878515044962, 6832.7110418010525, 867.3112854544419, 875.2166810704384, 900.7027680865299, 936.1901954809041, 936.3164889630608, 27807.72192278092, 28773.712262877103, 28854.134375968755, 29590.704092959226, 29665.406691866043, 824.8432797768405, 1031.3605564550028, 1042.5100202904775, 1047.4082143588848, 1075.8440026196158, 14639.48761793332, 14659.926700366643, 14677.0, 15018.849811501963, 15064.774028389584, 607162.0, 612938.0, 616240.0, 620013.0, 621024.0, 46726.0, 53779.731101344245, 53797.73101134547, 53805.73097134489, 53822.730886345315, 1157.438642258504, 1251.161721646499, 1253.2355263289387, 1309.4369421148997, 1369.7465968795805, 29041.0, 30151.0, 30217.848910755307, 30537.847310763304, 30764.538531922488, 46266.53733462622, 46765.76617116893, 46799.53200467952, 46838.829029274544, 46928.7260474627, 515443.0, 517208.0, 519935.0, 520744.0, 521297.0, 24360.147394841097, 25346.1128860489, 25362.873185633955, 25905.48189036171, 25930.0, 43727.0, 44335.77832110819, 44960.0, 45604.315935261664, 46700.53299466962, 36559.34406559323, 36708.0, 36821.55310925328, 37774.62225377711, 38148.42777358397, 35772.64227357797, 36899.893003209945, 37016.62983370132, 37743.81128094342, 37930.62069379381, 30360.54459183158, 36126.0, 36369.0, 37144.0, 37226.0, 31572.526412104296, 33015.20287011098, 33304.83347583246, 33491.1627209312, 33551.16122096967, 20130.899345503178, 22293.0, 24564.87717561401, 25165.370865728502, 26164.869175654], "value": 18955.1409192695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.2414364254996, 362.7968337730869, 366.5125383240291, 370.8053272032184, 388.8930544100372, 14605.488807891712, 14877.851221487781, 15789.60525986851, 19886.90056549715, 20384.388468345973, 19049.52376190596, 19614.117364718586, 20038.799612003877, 20293.594128117427, 20499.692504612405, 4814.470408255098, 5953.3451320354725, 6003.549733769963, 6009.57932944694, 6013.338532761392, 5161.561267292284, 5812.709364531777, 5818.272715910512, 5879.059350503899, 5890.587658863881, 5594.916076258859, 5819.33077696064, 5828.7668493260435, 5830.708464576775, 5838.883222335551, 5772.278465191851, 5885.5880088393615, 5891.705414729289, 5893.882122357529, 5896.616719913192, 4875.975620121877, 5051.797928082872, 5125.846224613268, 5137.075326441239, 5149.819756308549, 25567.488650226893, 25585.36036599081, 25627.0, 25987.350316242057, 26055.73944260552, 2150.8386870984737, 2189.693442917993, 2243.05791567542, 2256.4020534558354, 2372.181597348912, 15061.548153555412, 15647.374105035786, 15780.210989450596, 15791.763123553155, 15908.0, 1733.6099377640055, 1770.8583313334902, 1801.0634470075618, 1877.4836919847014, 1903.551409293338, 115325.0, 120218.0, 121169.39415302867, 121572.0, 122334.38832805867, 7078.539894906815, 9645.807083858284, 10011.299209055296, 10065.849012264822, 10613.416262105658, 12363.876361236273, 12459.501619935283, 12472.314022728837, 12476.563320283747, 12483.812742808865, 7885.329746971572, 8021.598920053974, 8083.353331733447, 8160.591970401456, 8191.83616327682, 8354.373421993374, 8368.53973031477, 8407.41148119638, 8534.975802903691, 8554.529500877386, 3824.330742120118, 3881.437191607201, 3902.1025164211824, 3952.486176796998, 3955.129871428281, 1916.7987361327139, 1938.2925232290395, 1939.2049259803696, 1943.8833669979847, 1976.3478052242726, 722.8229083874485, 840.9243168114899, 868.4745728834091, 894.8434024045766, 927.7726956895604, 20565.981233779137, 21372.78627213739, 21874.219241877516, 21985.010674519686, 22441.77558224397, 846.8137009857821, 925.4308600210957, 937.5687183895349, 951.8191543606692, 991.5587563534158, 10937.4531273436, 11289.832778250764, 11333.886661133449, 11384.601538946097, 11411.087113030937, 574135.0, 574970.50294971, 575157.1242143762, 584409.0, 585152.0, 77810.83283750636, 119453.0, 122229.77770222184, 127669.0, 130599.0, 754.864124457592, 963.4749061761216, 1000.0949141027381, 1003.7390278527489, 1005.6128390569684, 18503.167357468676, 19403.4178974631, 19752.506187345432, 19824.405267841987, 19852.503687407352, 38614.0, 46549.3017604743, 47536.7623161882, 52287.0, 52800.47199527956, 69855.60288794094, 75215.8717619247, 80552.79170812326, 82429.58785206036, 84186.73719894084, 6976.720931162697, 7796.970953734696, 7993.72021979228, 8018.318442932183, 8345.415820892722, 10377.0, 12316.938415308225, 12755.48978040905, 13256.602301930958, 13314.800277995644, 8863.911360886308, 9796.951015244878, 10945.01494865466, 11082.445877706245, 11165.665030049111, 5050.49495050492, 9315.86026209621, 10465.581376744845, 10992.890071099187, 11070.003699667068, 10827.945860270649, 11210.943945280222, 11295.943520282675, 11423.314601123959, 11434.94282528582, 7267.255858594005, 7291.08861392326, 7307.634618268959, 7511.436642251962, 7568.735094271674, 8796.868046979173, 8823.823523529622, 10173.898261017592, 10760.246782725135, 10817.51321190564], "value": 9242.768225612906, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.5371696764612, 1494.9551513454596, 1496.5884381795006, 1675.5392267126538, 1830.057520377006, 7749.30256276935, 8179.0, 8436.987561492622, 8710.041895391501, 8725.301975841934, 8596.3122950164, 8649.524276164808, 8661.263792577623, 8672.43629164104, 8677.392582519225, 2326.4183954011496, 2361.126383238204, 2361.49227915998, 2361.752016038317, 2363.7990770784504, 2312.4796920692793, 2342.625179971205, 2359.846609970355, 2360.3391050505884, 2361.445060410802, 2321.3384185507134, 2339.4502291961376, 2354.423166324253, 2356.469794296278, 2367.6803631509783, 2283.5318759654397, 2318.48993221491, 2323.5701395241936, 2328.2549584133167, 2343.086196383412, 2304.055337311694, 2307.361773141072, 2309.145616122029, 2312.3294244669028, 2312.4450131968374, 8607.010193827664, 8640.438371505832, 8709.216170544618, 8717.389782715212, 8722.08418116095, 5064.594832413398, 5083.79664813407, 5235.712035838027, 5240.266362709223, 5246.947530524722, 3534.5228394166716, 3632.146445585285, 3632.6367363263726, 3636.072801435617, 3655.3968595181764, 1059.5549869055014, 1063.5533076108054, 1073.3452593917705, 1082.6048492300333, 1100.8128618134856, 11537.423128843524, 11541.538338466451, 11617.535298588047, 11620.128490363257, 11638.825417618742, 4400.3399490076745, 4568.771561421916, 4617.584417402382, 4811.037792441526, 4848.684835485682, 8952.373333866697, 9026.232770214605, 9026.729198124067, 9027.45835249874, 9030.45817250967, 5194.246834209019, 5196.506331898464, 5197.922031169535, 5201.6358854880555, 5204.375474943031, 4072.3687828386646, 4357.629601483846, 4376.934345984812, 4495.910081798411, 4872.439669437989, 3620.402633565485, 3657.634236576321, 3709.1283548366114, 3746.756460830092, 3750.287445385368, 1138.0895283772948, 1141.4749215360862, 1142.7257458210136, 1152.3489228585822, 1157.1841851494676, 828.8010877389383, 847.7329641163014, 877.3113106211594, 900.401233179928, 915.7893684452613, 5660.660360378391, 5701.258836351248, 5971.731272092767, 5989.610675306091, 6009.939900601025, 803.6062329458523, 877.464437234839, 902.3503077783985, 914.3416739947228, 934.2759361494896, 3836.3478208704587, 3844.9231015379537, 3851.247682304601, 3894.299026175259, 3950.802459876995, 9238.353315267866, 10411.47942602882, 10627.14982801374, 10803.513841877135, 10814.134869210602, 10653.946730266454, 10739.785204295871, 10740.838887416696, 10749.62376316825, 10756.247062705535, 6764.526483146231, 7655.540667559852, 8172.060213075453, 8175.427720059539, 8183.83632327362, 5786.884262314646, 6052.122442245938, 6070.848228794315, 6117.510599152146, 6125.1424800527075, 9378.765530861783, 9711.465869377253, 9979.900200997898, 10015.298929074896, 10073.647422340184, 9992.050755178247, 10151.64469243573, 10157.847632285373, 10263.84604230952, 10328.277020608486, 7405.740799072007, 7440.925590744023, 7494.400447964041, 7551.962240188764, 7640.121386040676, 8067.87898181516, 8136.552489612891, 8147.633356498854, 8158.265756081981, 8158.592070396575, 7201.6399180041835, 7372.225916278713, 7430.96284518574, 7478.925210748041, 7480.476366654282, 7179.748708795167, 7223.575450225603, 7250.274972502708, 7316.304951029643, 7326.560406375635, 6804.353586409283, 6964.408025317702, 6968.128983877015, 6971.930280697331, 7001.50989430735, 4940.851774446773, 4982.576480999156, 5016.272640467185, 5033.597312215087, 5106.183010718267, 4918.458969513422, 4969.1800852860315, 5032.043911656773, 5184.274201611853, 5201.375834899836], "value": 4507.920929816004, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3069072334652, 302.5159744408946, 305.1166871905832, 310.077519379845, 313.48588315163124, 4374.540673229313, 5243.8951220975605, 5280.551153151977, 5296.761645725942, 5318.76065577049, 4994.677474974136, 5193.610479214055, 5247.396549396821, 5259.631825772198, 5280.287161233232, 3450.9482357764655, 3650.6166852480374, 3739.233457141293, 3930.8231129599235, 4050.3721923101966, 3399.8980030598996, 3697.1681371691425, 4034.2334956358345, 4047.089404883907, 4050.6961977851633, 3305.3058857639803, 3308.4541050726602, 3321.2195134143576, 3595.7123430125657, 4009.9198016039663, 3552.982235088808, 3781.5840257571645, 3787.6780473659774, 3850.691944644436, 4046.2514434829504, 3090.3664748726455, 3092.38152369527, 3223.0169798211537, 3397.575303087114, 3527.276908233806, 4713.457952335457, 5145.253938178943, 5184.818531351422, 5199.896002079975, 5228.712420816853, 4447.355133505656, 4620.976895115503, 4625.722456652612, 4645.628349732014, 4648.558386953234, 5130.589552835801, 5450.836474905759, 5463.043967305705, 5517.682573319215, 5547.972260138714, 3317.6397676952574, 3875.9806200969097, 3893.143508428141, 3903.4144878268203, 3937.330653788863, 5618.887622247573, 5817.301923769132, 5846.444587764156, 5879.823605291848, 5886.529077673818, 3899.512560929884, 3919.490466239428, 3925.666318362971, 3928.3125453045604, 3967.5834037425657, 3338.8998330050135, 3401.863925443004, 3964.20715856824, 3975.6620687241907, 3976.681865450757, 4554.407926969473, 4585.93632865212, 4604.112313951264, 4630.374899388539, 4643.257078867365, 5314.601404894571, 5428.240046393508, 5520.613557051048, 5542.61201715876, 5604.663720176803, 1332.6468485851258, 1347.8517363090052, 1354.9066691960943, 1358.1443690475135, 1362.9777666749937, 666.8799616069155, 778.5678948183812, 793.8608097380278, 829.1708291708292, 835.5738573327525, 3909.92180156401, 3960.108975480494, 4036.515618125785, 4374.868753937323, 5083.364579427572, 4320.049589090395, 4700.38070685577, 4734.857954261378, 5674.631148975303, 5680.636647204641, 5165.468920229136, 5171.974140129275, 5190.987757387274, 5195.844124676266, 5203.453637368098, 2498.7880877774264, 2644.405008873027, 2719.6872359678496, 2772.556390977434, 2895.247235718686, 4689.788959496763, 5361.212169671749, 5863.970680146572, 5883.588148829541, 5887.264091988502, 4486.75322857246, 5292.935755055531, 5771.538276937918, 5771.62360019385, 5786.594938354359, 5922.851928701731, 5968.492678122321, 5976.641401515923, 5983.700814959322, 6013.548983826143, 5729.4843464088435, 5735.340435849931, 5753.820466804379, 5755.625884317506, 5759.683217423082, 4710.4818469967595, 5312.979234623244, 5577.462254674195, 5600.467955544217, 5660.745266462936, 4752.500987396274, 5569.458388903067, 5901.203337549376, 5929.792457264148, 5944.613600115978, 2913.4464451754097, 3665.725070619661, 3859.536855577349, 3873.9806300968316, 3881.3013657541915, 4381.802818873095, 4947.773501924221, 5170.37955445334, 5787.623804452697, 5792.652440853562, 5518.806841760519, 5537.723113844211, 5550.306211723535, 5558.305211848519, 5581.358143813513, 5287.550558202442, 5548.917960997566, 5556.777728890961, 5562.2490963721, 5579.16312553104, 4367.694261401672, 5448.4006759257245, 5461.344638643388, 5483.026110600101, 5486.286782718221, 5240.7641656126325, 5436.0, 5568.331800184003, 5569.860753481195, 5595.300587426572, 4270.167317373082, 4471.955280447154, 4916.631252656137, 5162.535371816554, 5213.947860521498], "value": 3986.913372348139, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.3713800674078, 728.1553398058253, 755.8639444899916, 777.5412506621093, 814.4909431605247, 12507.624771256877, 12601.810972835416, 12670.366481675914, 15959.601009974756, 15973.760393594106, 14913.627159321022, 14990.175540345277, 15326.770098448504, 15370.385184592631, 15426.537203883901, 822.3626689315769, 850.1328644782318, 852.0840096895831, 853.1127627267634, 853.8975322961253, 831.4969443486694, 832.4797001873828, 833.5623797506297, 834.3992325525642, 835.1648351648352, 884.9469031858077, 887.8845750052484, 888.0630934364275, 888.2627419242046, 889.1952782731606, 840.3361344537808, 872.3326655108815, 881.4446898453965, 884.6549845560237, 884.8274586455643, 647.4431988489895, 649.5810202419412, 650.3171669746795, 650.8633187030729, 651.3681728723132, 11809.173357864953, 14216.075955062888, 14271.928640356835, 14280.500182493666, 14284.357203925849, 11093.0, 13510.662233444144, 13529.59411217665, 13936.58190254294, 14050.578482645536, 11698.0, 11722.941385293105, 11784.175107742376, 11868.643940681794, 11966.691476554659, 3672.866079738019, 3710.424884142962, 3768.7550309229814, 3780.2250538639514, 3826.4642949987024, 109757.45121274423, 111212.0, 113659.9481141492, 122355.77644223622, 122913.0, 10619.150467962545, 10778.137748980063, 10789.83815242772, 10965.122790176767, 11087.556497740161, 21318.360449186548, 21473.570528589655, 21692.34922952314, 21712.89143554304, 21738.13047478083, 7679.692812287558, 7698.307152356315, 7769.805754856061, 7826.921730782733, 7980.600969951595, 4316.827326906889, 4342.500612429548, 4377.365282034151, 4400.141972315432, 4512.34570987205, 3493.7379696522353, 3644.0707619557375, 3645.289168612095, 3646.3072016316814, 3647.4893514907935, 544.5480251391335, 659.5053709717712, 666.2105405094978, 668.3350066683681, 668.3984414027415, 575.6603603873651, 620.652434636605, 649.5810202419412, 662.2185820731487, 678.575890068707, 8952.641894324284, 9128.634854605742, 9251.167394934488, 9257.629694812267, 9265.861012084824, 626.1984659635674, 652.6164531193899, 658.4962503684685, 668.6389349751178, 672.4519516594058, 4304.849330273425, 4547.726636541774, 4548.249538826123, 4615.14619795339, 4646.86059418218, 99800.0, 109163.45418273017, 126820.0, 145887.94168027793, 163773.0, 139366.0, 140691.0, 140859.0, 140934.0, 141941.0, 118278.40860795641, 118560.40719796518, 120216.3989180066, 120255.0, 120664.0, 7571.772846814604, 8684.826303474021, 8715.869261960948, 8787.868181977148, 8857.535173429476, 41105.972350691474, 54409.10318071392, 62668.0, 65579.03262902121, 67425.3257467419, 75641.0, 94569.0, 97125.02874971443, 101818.0, 102508.48745756224, 11081.002709756161, 11223.326600404002, 11496.770064598828, 11498.655040348804, 11735.931636118623, 12282.877171228174, 12478.625641230778, 12646.0, 12664.587406048027, 12748.936255318664, 11865.703357416134, 11879.406029698654, 11938.940305298418, 12046.638600841989, 12269.57056503018, 11601.303921764722, 11846.407679615812, 12184.512619495476, 12192.08559358034, 12344.876551234733, 11616.5353385867, 11687.357195353998, 11769.0, 11933.701657458632, 12093.153479256367, 9698.078682525149, 10307.226957978331, 10364.637237696947, 10447.94776026115, 10531.736706582396, 9474.289428292768, 10123.443210623487, 10138.442385668859, 10145.188384929335, 10148.32552628808], "value": 7564.451788485056, "unit": "score"}], "name": "dromaeo_css", "value": 7147.906257173671, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 117.0, 163.0, 171.0, 113.0, 112.0, 114.0, 112.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 111.0, 148.0, 113.0, 106.0, 96.0, 134.0, 176.0, 142.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 184.0, 184.0, 182.0, 185.0, 183.0, 218.0, 183.0, 184.0], "value": 187.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 90.0, 76.0, 86.0, 81.0, 81.0, 84.0, 59.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 96.0, 70.0, 68.0, 69.0, 92.0, 70.0, 96.0, 126.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 87.0, 90.0, 87.0, 91.0, 87.0, 89.0, 86.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 87.0, 83.0, 85.0, 71.0, 136.0, 134.0, 103.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 54.0, 56.0, 55.0, 54.0, 54.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 75.0, 103.0, 107.0, 101.0, 73.0, 71.0, 69.0, 69.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 90.0, 90.0, 86.0, 85.0, 88.0, 92.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 156.0, 134.0, 148.0, 153.0, 152.0, 135.0, 146.0, 179.0, 148.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 171.0, 168.0, 173.0, 170.0, 230.0, 171.0, 180.0, 235.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 69.0, 69.0, 67.0, 69.0, 69.0, 69.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1504.9000000000003, "alertThreshold": 2.0}]} 12:34:16 INFO - Return code: 0 12:34:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:16 INFO - # TBPL SUCCESS # 12:34:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:34:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:34:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:34:16 INFO - 2016-10-13 12:34:16.595 screenresolution[1671:9863] starting screenresolution argv=screenresolution get 12:34:16 INFO - 2016-10-13 12:34:16.600 screenresolution[1671:9863] Display 0: 1600x1200x32@60 12:34:16 INFO - 2016-10-13 12:34:16.606 screenresolution[1672:9866] starting screenresolution argv=screenresolution list 12:34:16 INFO - Available Modes on Display 0 12:34:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:34:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:34:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:34:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:34:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:34:16 INFO - Intel Iris: 12:34:16 INFO - Chipset Model: Intel Iris 12:34:16 INFO - Type: GPU 12:34:16 INFO - Bus: Built-In 12:34:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:34:16 INFO - Vendor: Intel (0x8086) 12:34:16 INFO - Device ID: 0x0a2e 12:34:16 INFO - Revision ID: 0x0009 12:34:16 INFO - Displays: 12:34:16 INFO - Display: 12:34:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:34:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:34:16 INFO - Display Serial Number: 1600x1200 60 12:34:16 INFO - Main Display: Yes 12:34:16 INFO - Mirror: Off 12:34:16 INFO - Online: Yes 12:34:16 INFO - Rotation: Supported 12:34:16 INFO - Return code: 0 12:34:16 INFO - Running post-action listener: _resource_record_post_action 12:34:16 INFO - [mozharness: 2016-10-13 19:34:16.852900Z] Finished run-tests step (success) 12:34:16 INFO - Running post-run listener: _resource_record_post_run 12:34:16 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37467136; Write bytes: 708020736; Read time: 241; Write time: 1924 12:34:16 INFO - TinderboxPrint: CPU usage
53.5% 12:34:16 INFO - TinderboxPrint: I/O read bytes / time
37,467,136 / 241 12:34:16 INFO - TinderboxPrint: I/O write bytes / time
708,020,736 / 1,924 12:34:16 INFO - TinderboxPrint: CPU idle
2,260.4 (46.5%) 12:34:16 INFO - TinderboxPrint: CPU system
975.7 (20.1%) 12:34:16 INFO - TinderboxPrint: CPU user
1,625.6 (33.4%) 12:34:16 INFO - TinderboxPrint: Swap in / out
443,744,256 / 0 12:34:16 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 392380928; Write bytes: 381880320; Read time: 30434; Write time: 742 12:34:16 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31181824; Write bytes: 297525760; Read time: 184; Write time: 1117 12:34:17 INFO - Running post-run listener: _upload_blobber_files 12:34:17 INFO - Blob upload gear active. 12:34:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:17 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 12:34:17 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'] 12:34:17 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 12:34:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:18 INFO - (blobuploader) - INFO - Done attempting. 12:34:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:19 INFO - (blobuploader) - INFO - Done attempting. 12:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:21 INFO - (blobuploader) - INFO - Done attempting. 12:34:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:21 INFO - Return code: 0 12:34:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5eba5b070c5c38992195bf552c1d122767866ba93c6c72beafcf065e854a4015d748c03247dcf3c31371d42caa9e6f82fcfd51a852ba2b3aea714705627ef833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972b914a2bb28443f5312ad4e14f59f9ad7127ef828654b3b42ce9249d28ef4896d3163a54a793a5c3e0263f979b6d2cd38e29f98daab4c278a417ac9271d5a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d689a32320b625a30c570f8e1872fa0a0dd7d0dc4c58024ce02f9356506e0922f70a7dd3531f7bc98963e82eee98a40fccbe26960b66c8f3a6db09c916a05561"} 12:34:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:21 INFO - Contents: 12:34:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5eba5b070c5c38992195bf552c1d122767866ba93c6c72beafcf065e854a4015d748c03247dcf3c31371d42caa9e6f82fcfd51a852ba2b3aea714705627ef833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972b914a2bb28443f5312ad4e14f59f9ad7127ef828654b3b42ce9249d28ef4896d3163a54a793a5c3e0263f979b6d2cd38e29f98daab4c278a417ac9271d5a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d689a32320b625a30c570f8e1872fa0a0dd7d0dc4c58024ce02f9356506e0922f70a7dd3531f7bc98963e82eee98a40fccbe26960b66c8f3a6db09c916a05561"} 12:34:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:21 INFO - Copying logs to upload dir... 12:34:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.781477 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-13 12:34:21.945902) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 12:34:21.949258) ========= 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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5eba5b070c5c38992195bf552c1d122767866ba93c6c72beafcf065e854a4015d748c03247dcf3c31371d42caa9e6f82fcfd51a852ba2b3aea714705627ef833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972b914a2bb28443f5312ad4e14f59f9ad7127ef828654b3b42ce9249d28ef4896d3163a54a793a5c3e0263f979b6d2cd38e29f98daab4c278a417ac9271d5a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d689a32320b625a30c570f8e1872fa0a0dd7d0dc4c58024ce02f9356506e0922f70a7dd3531f7bc98963e82eee98a40fccbe26960b66c8f3a6db09c916a05561"} build_url:https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010996 build_url: 'https://queue.taskcluster.net/v1/task/Gl_xj9K4TrOPiEobECEO6w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5eba5b070c5c38992195bf552c1d122767866ba93c6c72beafcf065e854a4015d748c03247dcf3c31371d42caa9e6f82fcfd51a852ba2b3aea714705627ef833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972b914a2bb28443f5312ad4e14f59f9ad7127ef828654b3b42ce9249d28ef4896d3163a54a793a5c3e0263f979b6d2cd38e29f98daab4c278a417ac9271d5a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d689a32320b625a30c570f8e1872fa0a0dd7d0dc4c58024ce02f9356506e0922f70a7dd3531f7bc98963e82eee98a40fccbe26960b66c8f3a6db09c916a05561"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 12:34:21.980920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:34:21.981222) ========= 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.RnBCpXrNP2/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.tUfEPqmFIy/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:34:22.029524) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 12:34:22.029824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 12:34:22.030134) ========= ========= Total master_lag: 0.17 =========