builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1479219263.99 results: success (0) buildid: 20161115032714 builduid: 21d4aac9ac4144faa0faa5f234c99a97 revision: e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:23.990175) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:23.990775) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:23.991153) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:24.017631) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:24.018300) ========= 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.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-11-15 06:14:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.9M=0s 2016-11-15 06:14:24 (29.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119938 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:24.164732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:24.165248) ========= 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.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.026359 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:24.215418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-15 06:14:24.215777) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-11-15 06:14:24,308 truncating revision to first 12 chars 2016-11-15 06:14:24,309 Setting DEBUG logging. 2016-11-15 06:14:24,309 attempt 1/10 2016-11-15 06:14:24,309 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e16d1a881481?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 06:14:26,518 unpacking tar archive at: mozilla-central-e16d1a881481/testing/mozharness/ program finished with exit code 0 elapsedTime=2.474485 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-15 06:14:26.714046) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:26.714729) ========= script_repo_revision: e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:26.715606) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:26.716218) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 06:14:26.735833) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-15 06:14:26.736146) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:14:26 INFO - MultiFileLogger online at 20161115 06:14:26 in /builds/slave/test 06:14:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:14:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:26 INFO - {'append_to_log': False, 06:14:26 INFO - 'base_work_dir': '/builds/slave/test', 06:14:26 INFO - 'blob_upload_branch': 'Firefox', 06:14:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:26 INFO - 'branch': 'Firefox', 06:14:26 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:26 INFO - 'config_files': ('talos/mac_config.py',), 06:14:26 INFO - 'default_actions': ('clobber', 06:14:26 INFO - 'read-buildbot-config', 06:14:26 INFO - 'download-and-extract', 06:14:26 INFO - 'populate-webroot', 06:14:26 INFO - 'create-virtualenv', 06:14:26 INFO - 'install', 06:14:26 INFO - 'run-tests'), 06:14:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:26 INFO - 'download_minidump_stackwalk': True, 06:14:26 INFO - 'download_symbols': 'ondemand', 06:14:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:26 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:26 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:26 INFO - 'installer_path': 'installer.exe', 06:14:26 INFO - 'log_level': 'info', 06:14:26 INFO - 'log_name': 'talos', 06:14:26 INFO - 'log_to_console': True, 06:14:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:14:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:14:26 INFO - 'opt_config_files': (), 06:14:26 INFO - 'pip_index': False, 06:14:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:26 INFO - 'cmd': ('bash', 06:14:26 INFO - '-c', 06:14:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:26 INFO - 'enabled': True, 06:14:26 INFO - 'halt_on_failure': False, 06:14:26 INFO - 'name': 'check_screen_resolution'},), 06:14:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:26 INFO - 'cmd': ('bash', 06:14:26 INFO - '-c', 06:14:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:26 INFO - 'enabled': True, 06:14:26 INFO - 'halt_on_failure': False, 06:14:26 INFO - 'name': 'check_screen_resolution'},), 06:14:26 INFO - 'run_cmd_checks_enabled': True, 06:14:26 INFO - 'sps_profile': False, 06:14:26 INFO - 'sps_profile_interval': 0, 06:14:26 INFO - 'suite': 'dromaeojs', 06:14:26 INFO - 'system_bits': '32', 06:14:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:26 INFO - 'title': 't-yosemite-r7-0127', 06:14:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:26 INFO - 'use_talos_json': True, 06:14:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:26 INFO - 'work_dir': 'build'} 06:14:26 INFO - [mozharness: 2016-11-15 14:14:26.942987Z] Running clobber step. 06:14:26 INFO - Running pre-action listener: _resource_record_pre_action 06:14:26 INFO - Running main action method: clobber 06:14:26 INFO - rmtree: /builds/slave/test/build 06:14:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:29 INFO - Running post-action listener: _resource_record_post_action 06:14:29 INFO - [mozharness: 2016-11-15 14:14:29.311368Z] Finished clobber step (success) 06:14:29 INFO - [mozharness: 2016-11-15 14:14:29.311545Z] Running read-buildbot-config step. 06:14:29 INFO - Running pre-action listener: _resource_record_pre_action 06:14:29 INFO - Running main action method: read_buildbot_config 06:14:29 INFO - Using buildbot properties: 06:14:29 INFO - { 06:14:29 INFO - "project": "", 06:14:29 INFO - "product": "firefox", 06:14:29 INFO - "script_repo_revision": "production", 06:14:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:14:29 INFO - "repository": "", 06:14:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:14:29 INFO - "buildid": "20161115032714", 06:14:29 INFO - "pgo_build": "False", 06:14:29 INFO - "basedir": "/builds/slave/test", 06:14:29 INFO - "buildnumber": 16, 06:14:29 INFO - "slavename": "t-yosemite-r7-0127", 06:14:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:14:29 INFO - "platform": "yosemite_r7", 06:14:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:14:29 INFO - "branch": "mozilla-central", 06:14:29 INFO - "repo_path": "mozilla-central", 06:14:29 INFO - "revision": "e16d1a881481d7d83493481d9cc2c4ef1e6b9a36", 06:14:29 INFO - "stage_platform": "macosx64", 06:14:29 INFO - "builduid": "21d4aac9ac4144faa0faa5f234c99a97", 06:14:29 INFO - "slavebuilddir": "test" 06:14:29 INFO - } 06:14:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:14:29 INFO - Running post-action listener: _resource_record_post_action 06:14:29 INFO - [mozharness: 2016-11-15 14:14:29.315240Z] Finished read-buildbot-config step (success) 06:14:29 INFO - [mozharness: 2016-11-15 14:14:29.315374Z] Running download-and-extract step. 06:14:29 INFO - Running pre-action listener: _resource_record_pre_action 06:14:29 INFO - Running main action method: download_and_extract 06:14:29 INFO - mkdir: /builds/slave/test/build/tests 06:14:29 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')]} 06:14:29 INFO - trying https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 06:14:30 INFO - Downloaded 1199 bytes. 06:14:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:30 INFO - Using the following test package requirements: 06:14:30 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:14:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:14:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'jsshell-mac.zip'], 06:14:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:14:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:14:30 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:14:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:14:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:30 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:14:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:14:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:30 INFO - Fetch https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:30 INFO - Http code: 200 06:14:30 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 06:14:30 INFO - x-amz-cf-id: nvFvQYMW6xEYoV5hGWVHQ-tCU7lFh4oX_hz-C52Lc_JJjoXFIctMcw== 06:14:30 INFO - x-amz-version-id: gqPOwMaFJHY.56W8UEmW7U2h9DsJODLl 06:14:31 INFO - Expected file size: 16330862 06:14:31 INFO - Obtained file size: 16330862 06:14:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:14:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:38 INFO - Fetch https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:38 INFO - Http code: 200 06:14:38 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 06:14:38 INFO - x-amz-cf-id: gwOzY1hyQ3n0tgEwVbNLcDmLVFetpBAXsd0xDU_XFnBL1WSOUppAdw== 06:14:38 INFO - x-amz-version-id: gqPOwMaFJHY.56W8UEmW7U2h9DsJODLl 06:14:38 INFO - Expected file size: 16330862 06:14:38 INFO - Obtained file size: 16330862 06:14:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:14:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:14:45 INFO - Fetch https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:14:46 INFO - Http code: 200 06:14:46 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 06:14:46 INFO - x-amz-cf-id: cJne9u5CY2cCr2Du_MjzDdE1stH0BbTdzE6DdD6v3nBs9CrqZemmyQ== 06:14:46 INFO - x-amz-version-id: Lsalozmy3cThepudhiBh2Ktp0kcJDCn1 06:14:46 INFO - Expected file size: 14409038 06:14:46 INFO - Obtained file size: 14409038 06:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:46 INFO - trying https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:46 INFO - Downloading https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:14:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 06:14:49 INFO - Downloaded 110980067 bytes. 06:14:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:49 INFO - mkdir: /builds/slave/test/properties 06:14:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:49 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:49 INFO - Contents: 06:14:49 INFO - build_url:https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:50 INFO - Running post-action listener: _resource_record_post_action 06:14:50 INFO - Running post-action listener: set_extra_try_arguments 06:14:50 INFO - [mozharness: 2016-11-15 14:14:50.469754Z] Finished download-and-extract step (success) 06:14:50 INFO - [mozharness: 2016-11-15 14:14:50.469883Z] Running populate-webroot step. 06:14:50 INFO - Running pre-action listener: _resource_record_pre_action 06:14:50 INFO - Running main action method: populate_webroot 06:14:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:50 INFO - '--tppagecycles', 06:14:50 INFO - '7'], 06:14:50 INFO - 'tests': ['tsvgm']}}, 06:14:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['damp', 'tps']}, 06:14:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'tests': ['damp', 'tps']}, 06:14:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['dromaeo_dom']}, 06:14:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['basic_compositor_video']}, 06:14:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['a11yr', 06:14:50 INFO - 'ts_paint', 06:14:50 INFO - 'tpaint', 06:14:50 INFO - 'sessionrestore', 06:14:50 INFO - 'sessionrestore_no_auto_restore', 06:14:50 INFO - 'tabpaint']}, 06:14:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['tsvgx', 06:14:50 INFO - 'tsvgr_opacity', 06:14:50 INFO - 'tart', 06:14:50 INFO - 'tscrollx', 06:14:50 INFO - 'cart']}, 06:14:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:50 INFO - 'tsvgr_opacity', 06:14:50 INFO - 'tart', 06:14:50 INFO - 'tscrollx', 06:14:50 INFO - 'cart']}, 06:14:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'talos_options': ['--disable-e10s'], 06:14:50 INFO - 'tests': ['tp5o']}, 06:14:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'tests': ['tp5o']}, 06:14:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'talos_options': ['--disable-e10s', 06:14:50 INFO - '--xperf_path', 06:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:50 INFO - 'tests': ['tp5n']}, 06:14:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:50 INFO - 'talos_options': ['--xperf_path', 06:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:50 INFO - 'tests': ['tp5n']}}, 06:14:50 INFO - 'talos.zip': {'path': '', 06:14:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:50 INFO - Running post-action listener: _resource_record_post_action 06:14:50 INFO - [mozharness: 2016-11-15 14:14:50.488732Z] Finished populate-webroot step (success) 06:14:50 INFO - [mozharness: 2016-11-15 14:14:50.488865Z] Running create-virtualenv step. 06:14:50 INFO - Running pre-action listener: _resource_record_pre_action 06:14:50 INFO - Running main action method: create_virtualenv 06:14:50 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:50 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 06:14:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:50 INFO - Using real prefix '/tools/python27' 06:14:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:51 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:54 INFO - Installing pip.................done. 06:14:54 INFO - Return code: 0 06:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:54 INFO - Reading from file tmpfile_stdout 06:14:54 INFO - Output received: 06:14:54 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) 06:14:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:54 INFO - 'HOME': '/Users/cltbld', 06:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:54 INFO - 'LOGNAME': 'cltbld', 06:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:54 INFO - 'MOZ_NO_REMOTE': '1', 06:14:54 INFO - 'NO_EM_RESTART': '1', 06:14:54 INFO - 'PAGER': '/bin/cat', 06:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:54 INFO - 'PWD': '/builds/slave/test', 06:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:54 INFO - 'SHELL': '/bin/bash', 06:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:14:54 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:54 INFO - 'USER': 'cltbld', 06:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:54 INFO - 'XPC_FLAGS': '0x0', 06:14:54 INFO - 'XPC_SERVICE_NAME': '0', 06:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:54 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 06:14:54 INFO - Cleaning up... 06:14:54 INFO - Return code: 0 06:14:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:54 INFO - 'HOME': '/Users/cltbld', 06:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:54 INFO - 'LOGNAME': 'cltbld', 06:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:54 INFO - 'MOZ_NO_REMOTE': '1', 06:14:54 INFO - 'NO_EM_RESTART': '1', 06:14:54 INFO - 'PAGER': '/bin/cat', 06:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:54 INFO - 'PWD': '/builds/slave/test', 06:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:54 INFO - 'SHELL': '/bin/bash', 06:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:14:54 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:54 INFO - 'USER': 'cltbld', 06:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:54 INFO - 'XPC_FLAGS': '0x0', 06:14:54 INFO - 'XPC_SERVICE_NAME': '0', 06:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:55 INFO - Downloading/unpacking psutil>=3.1.1 06:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:58 INFO - Installing collected packages: psutil 06:14:58 INFO - Running setup.py install for psutil 06:14:58 INFO - building 'psutil._psutil_osx' extension 06:14:58 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 06:14:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:14:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:14:59 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 06:14:59 INFO - building 'psutil._psutil_posix' extension 06:14:59 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 06:14:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:14:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:14:59 INFO - ^ 06:14:59 INFO - 1 warning generated. 06:14:59 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 06:14:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:59 INFO - Successfully installed psutil 06:14:59 INFO - Cleaning up... 06:14:59 INFO - Return code: 0 06:14:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:59 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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:14:59 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 06:14:59 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 06:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:59 INFO - 'HOME': '/Users/cltbld', 06:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:59 INFO - 'LOGNAME': 'cltbld', 06:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:59 INFO - 'MOZ_NO_REMOTE': '1', 06:14:59 INFO - 'NO_EM_RESTART': '1', 06:14:59 INFO - 'PAGER': '/bin/cat', 06:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:59 INFO - 'PWD': '/builds/slave/test', 06:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:59 INFO - 'SHELL': '/bin/bash', 06:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:14:59 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:59 INFO - 'USER': 'cltbld', 06:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:59 INFO - 'XPC_FLAGS': '0x0', 06:14:59 INFO - 'XPC_SERVICE_NAME': '0', 06:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:15:02 INFO - Installing collected packages: mozsystemmonitor 06:15:02 INFO - Running setup.py install for mozsystemmonitor 06:15:02 INFO - Successfully installed mozsystemmonitor 06:15:02 INFO - Cleaning up... 06:15:02 INFO - Return code: 0 06:15:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:02 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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:02 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 06:15:02 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 06:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:02 INFO - 'HOME': '/Users/cltbld', 06:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:02 INFO - 'LOGNAME': 'cltbld', 06:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:02 INFO - 'MOZ_NO_REMOTE': '1', 06:15:02 INFO - 'NO_EM_RESTART': '1', 06:15:02 INFO - 'PAGER': '/bin/cat', 06:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:02 INFO - 'PWD': '/builds/slave/test', 06:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:02 INFO - 'SHELL': '/bin/bash', 06:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:02 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:02 INFO - 'USER': 'cltbld', 06:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:02 INFO - 'XPC_FLAGS': '0x0', 06:15:02 INFO - 'XPC_SERVICE_NAME': '0', 06:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:03 INFO - Downloading/unpacking jsonschema==2.5.1 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:15:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:15:05 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 06:15:05 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 06:15:05 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 06:15:05 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 06:15:05 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 06:15:05 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 06:15:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:15:06 INFO - warning: no files found matching '*.txt' 06:15:06 INFO - no previously-included directories found matching 'build' 06:15:06 INFO - no previously-included directories found matching 'dist' 06:15:06 INFO - no previously-included directories found matching '.git*' 06:15:06 INFO - Installing collected packages: jsonschema, functools32 06:15:06 INFO - Running setup.py install for functools32 06:15:06 INFO - warning: no files found matching '*.txt' 06:15:06 INFO - no previously-included directories found matching 'build' 06:15:06 INFO - no previously-included directories found matching 'dist' 06:15:06 INFO - no previously-included directories found matching '.git*' 06:15:06 INFO - Successfully installed jsonschema functools32 06:15:06 INFO - Cleaning up... 06:15:06 INFO - Return code: 0 06:15:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:15: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')]} 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15: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')]} 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:06 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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:06 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 06:15:06 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 06:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:06 INFO - 'HOME': '/Users/cltbld', 06:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:06 INFO - 'LOGNAME': 'cltbld', 06:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:06 INFO - 'MOZ_NO_REMOTE': '1', 06:15:06 INFO - 'NO_EM_RESTART': '1', 06:15:06 INFO - 'PAGER': '/bin/cat', 06:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:06 INFO - 'PWD': '/builds/slave/test', 06:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:06 INFO - 'SHELL': '/bin/bash', 06:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:06 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:06 INFO - 'USER': 'cltbld', 06:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:06 INFO - 'XPC_FLAGS': '0x0', 06:15:06 INFO - 'XPC_SERVICE_NAME': '0', 06:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:15:07 INFO - Cleaning up... 06:15:07 INFO - Return code: 0 06:15:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:07 INFO - 'HOME': '/Users/cltbld', 06:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:07 INFO - 'LOGNAME': 'cltbld', 06:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:07 INFO - 'MOZ_NO_REMOTE': '1', 06:15:07 INFO - 'NO_EM_RESTART': '1', 06:15:07 INFO - 'PAGER': '/bin/cat', 06:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:07 INFO - 'PWD': '/builds/slave/test', 06:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:07 INFO - 'SHELL': '/bin/bash', 06:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:07 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:07 INFO - 'USER': 'cltbld', 06:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:07 INFO - 'XPC_FLAGS': '0x0', 06:15:07 INFO - 'XPC_SERVICE_NAME': '0', 06:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:15:07 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 06:15:07 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 06:15:07 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 06:15:07 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 06:15:07 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 06:15:07 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 06:15:09 INFO - Downloading blobuploader-1.2.4.tar.gz 06:15:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:15:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:15:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:11 INFO - Downloading docopt-0.6.1.tar.gz 06:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:15:11 INFO - Installing collected packages: blobuploader, requests, docopt 06:15:11 INFO - Running setup.py install for blobuploader 06:15:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:15:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:15:11 INFO - Running setup.py install for requests 06:15:11 INFO - Running setup.py install for docopt 06:15:12 INFO - Successfully installed blobuploader requests docopt 06:15:12 INFO - Cleaning up... 06:15:12 INFO - Return code: 0 06:15:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:15:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:15:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:12 INFO - 'HOME': '/Users/cltbld', 06:15:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:12 INFO - 'LOGNAME': 'cltbld', 06:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:12 INFO - 'MOZ_NO_REMOTE': '1', 06:15:12 INFO - 'NO_EM_RESTART': '1', 06:15:12 INFO - 'PAGER': '/bin/cat', 06:15:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:12 INFO - 'PWD': '/builds/slave/test', 06:15:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:12 INFO - 'SHELL': '/bin/bash', 06:15:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:12 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:12 INFO - 'USER': 'cltbld', 06:15:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:12 INFO - 'XPC_FLAGS': '0x0', 06:15:12 INFO - 'XPC_SERVICE_NAME': '0', 06:15:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-EuOXHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-64xP3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-R_CTPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-2tOoor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-L9ulRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-4VDjxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-FKBGzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-qiTQA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-7h7mzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HHLva0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-elzUps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kfxkKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-19O_sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5PBtap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-NodHVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-UIlv_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-SyBFXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:15:14 INFO - Running setup.py install for manifestparser 06:15:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozcrash 06:15:14 INFO - Running setup.py install for mozdebug 06:15:14 INFO - Running setup.py install for mozdevice 06:15:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozfile 06:15:14 INFO - Running setup.py install for mozhttpd 06:15:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozinfo 06:15:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozInstall 06:15:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozleak 06:15:15 INFO - Running setup.py install for mozlog 06:15:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for moznetwork 06:15:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozprocess 06:15:15 INFO - Running setup.py install for mozprofile 06:15:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Running setup.py install for mozrunner 06:15:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Running setup.py install for mozscreenshot 06:15:16 INFO - Running setup.py install for moztest 06:15:16 INFO - Running setup.py install for mozversion 06:15:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:15:16 INFO - Cleaning up... 06:15:16 INFO - Return code: 0 06:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:16 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')]} 06:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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')]} 06:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:16 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 06:15:16 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 06:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:16 INFO - 'HOME': '/Users/cltbld', 06:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:16 INFO - 'LOGNAME': 'cltbld', 06:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:16 INFO - 'MOZ_NO_REMOTE': '1', 06:15:16 INFO - 'NO_EM_RESTART': '1', 06:15:16 INFO - 'PAGER': '/bin/cat', 06:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:16 INFO - 'PWD': '/builds/slave/test', 06:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:16 INFO - 'SHELL': '/bin/bash', 06:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:16 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:16 INFO - 'USER': 'cltbld', 06:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:16 INFO - 'XPC_FLAGS': '0x0', 06:15:16 INFO - 'XPC_SERVICE_NAME': '0', 06:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-K2NXxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-QvkHNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-QwO22t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-B8nTc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-24v_K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-MNy5KB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-cf8v0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-MzQyvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-MkzXXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-E0Dm7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sGxXpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-MnDZsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Go9z_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-YoT7yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Hj6rdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-M4GS3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-oONrly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:18 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)) 06:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:18 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)) 06:15:18 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)) 06:15:18 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)) 06:15:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:18 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 06:15:18 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 06:15:18 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 06:15:18 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 06:15:18 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 06:15:18 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 06:15:21 INFO - Downloading blessings-1.6.tar.gz 06:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:15:21 INFO - Installing collected packages: blessings 06:15:21 INFO - Running setup.py install for blessings 06:15:21 INFO - Successfully installed blessings 06:15:21 INFO - Cleaning up... 06:15:21 INFO - Return code: 0 06:15:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:15:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:22 INFO - Reading from file tmpfile_stdout 06:15:22 INFO - Current package versions: 06:15:22 INFO - blessings == 1.6 06:15:22 INFO - blobuploader == 1.2.4 06:15:22 INFO - docopt == 0.6.1 06:15:22 INFO - functools32 == 3.2.3-2 06:15:22 INFO - jsonschema == 2.5.1 06:15:22 INFO - manifestparser == 1.1 06:15:22 INFO - mozInstall == 1.12 06:15:22 INFO - mozcrash == 1.0 06:15:22 INFO - mozdebug == 0.1 06:15:22 INFO - mozdevice == 0.48 06:15:22 INFO - mozfile == 1.2 06:15:22 INFO - mozhttpd == 0.7 06:15:22 INFO - mozinfo == 0.9 06:15:22 INFO - mozleak == 0.1 06:15:22 INFO - mozlog == 3.4 06:15:22 INFO - moznetwork == 0.27 06:15:22 INFO - mozprocess == 0.23 06:15:22 INFO - mozprofile == 0.28 06:15:22 INFO - mozrunner == 6.13 06:15:22 INFO - mozscreenshot == 0.1 06:15:22 INFO - mozsystemmonitor == 0.3 06:15:22 INFO - moztest == 0.7 06:15:22 INFO - mozversion == 1.4 06:15:22 INFO - psutil == 3.1.1 06:15:22 INFO - requests == 1.2.3 06:15:22 INFO - wsgiref == 0.1.2 06:15:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:22 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')]} 06:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:22 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')]} 06:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:22 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 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:22 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 06:15:22 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 06:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:22 INFO - 'HOME': '/Users/cltbld', 06:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:22 INFO - 'LOGNAME': 'cltbld', 06:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:22 INFO - 'MOZ_NO_REMOTE': '1', 06:15:22 INFO - 'NO_EM_RESTART': '1', 06:15:22 INFO - 'PAGER': '/bin/cat', 06:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:22 INFO - 'PWD': '/builds/slave/test', 06:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:22 INFO - 'SHELL': '/bin/bash', 06:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:22 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:22 INFO - 'USER': 'cltbld', 06:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:22 INFO - 'XPC_FLAGS': '0x0', 06:15:22 INFO - 'XPC_SERVICE_NAME': '0', 06:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 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)) 06:15:22 INFO - Cleaning up... 06:15:22 INFO - Return code: 0 06:15:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:15:22 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')]} 06:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:22 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')]} 06:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f607490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb319c6b8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, '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.ikpeQfF3EB/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.RdtouW1bvm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:15:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:15:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:23 INFO - 'HOME': '/Users/cltbld', 06:15:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:23 INFO - 'LOGNAME': 'cltbld', 06:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:23 INFO - 'MOZ_NO_REMOTE': '1', 06:15:23 INFO - 'NO_EM_RESTART': '1', 06:15:23 INFO - 'PAGER': '/bin/cat', 06:15:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:23 INFO - 'PWD': '/builds/slave/test', 06:15:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:23 INFO - 'SHELL': '/bin/bash', 06:15:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:23 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:23 INFO - 'USER': 'cltbld', 06:15:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:23 INFO - 'XPC_FLAGS': '0x0', 06:15:23 INFO - 'XPC_SERVICE_NAME': '0', 06:15:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:15:23 INFO - Cleaning up... 06:15:23 INFO - Return code: 0 06:15:23 INFO - Running post-action listener: _resource_record_post_action 06:15:23 INFO - Running post-action listener: _start_resource_monitoring 06:15:23 INFO - Starting resource monitoring. 06:15:23 INFO - [mozharness: 2016-11-15 14:15:23.377354Z] Finished create-virtualenv step (success) 06:15:23 INFO - [mozharness: 2016-11-15 14:15:23.378308Z] Running install step. 06:15:23 INFO - Running pre-action listener: _resource_record_pre_action 06:15:23 INFO - Running main action method: install 06:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:23 INFO - Reading from file tmpfile_stdout 06:15:23 INFO - Detecting whether we're running mozinstall >=1.0... 06:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:15:23 INFO - Reading from file tmpfile_stdout 06:15:23 INFO - Output received: 06:15:23 INFO - Usage: mozinstall [options] installer 06:15:23 INFO - Options: 06:15:23 INFO - -h, --help show this help message and exit 06:15:23 INFO - -d DEST, --destination=DEST 06:15:23 INFO - Directory to install application into. [default: 06:15:23 INFO - "/builds/slave/test"] 06:15:23 INFO - --app=APP Application being installed. [default: firefox] 06:15:23 INFO - mkdir: /builds/slave/test/build/application 06:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:15:57 INFO - Reading from file tmpfile_stdout 06:15:57 INFO - Output received: 06:15:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:15:57 INFO - Running post-action listener: _resource_record_post_action 06:15:57 INFO - [mozharness: 2016-11-15 14:15:57.714098Z] Finished install step (success) 06:15:57 INFO - [mozharness: 2016-11-15 14:15:57.714257Z] Running run-tests step. 06:15:57 INFO - Running pre-action listener: _resource_record_pre_action 06:15:57 INFO - Running main action method: run_tests 06:15:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:57 INFO - 2016-11-15 06:15:57.728 screenresolution[1616:7312] starting screenresolution argv=screenresolution get 06:15:57 INFO - 2016-11-15 06:15:57.731 screenresolution[1616:7312] Display 0: 1600x1200x32@60 06:15:57 INFO - 2016-11-15 06:15:57.737 screenresolution[1617:7314] starting screenresolution argv=screenresolution list 06:15:57 INFO - Available Modes on Display 0 06:15:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:57 INFO - Intel Iris: 06:15:57 INFO - Chipset Model: Intel Iris 06:15:57 INFO - Type: GPU 06:15:57 INFO - Bus: Built-In 06:15:57 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:57 INFO - Vendor: Intel (0x8086) 06:15:57 INFO - Device ID: 0x0a2e 06:15:57 INFO - Revision ID: 0x0009 06:15:57 INFO - Displays: 06:15:57 INFO - Display: 06:15:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:57 INFO - Display Serial Number: 1600x1200 60 06:15:57 INFO - Main Display: Yes 06:15:57 INFO - Mirror: Off 06:15:57 INFO - Online: Yes 06:15:57 INFO - Rotation: Supported 06:15:57 INFO - Return code: 0 06:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:57 INFO - Python 2.7.3 06:15:57 INFO - Return code: 0 06:15:57 INFO - grabbing minidump binary from tooltool 06:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:57 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 0x10f41bd30>, '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 0x10f241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f413b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:57 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 06:15:57 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 06:15:57 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 06:15:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:58 INFO - Return code: 0 06:15:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:15:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:15:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 06:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 06:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ikpeQfF3EB/Render', 06:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:58 INFO - 'HOME': '/Users/cltbld', 06:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:58 INFO - 'LOGNAME': 'cltbld', 06:15:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:58 INFO - 'MOZ_NO_REMOTE': '1', 06:15:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:58 INFO - 'NO_EM_RESTART': '1', 06:15:58 INFO - 'PAGER': '/bin/cat', 06:15:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:58 INFO - 'PWD': '/builds/slave/test', 06:15:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:58 INFO - 'SHELL': '/bin/bash', 06:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RdtouW1bvm/Listeners', 06:15:58 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:58 INFO - 'USER': 'cltbld', 06:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:58 INFO - 'XPC_FLAGS': '0x0', 06:15:58 INFO - 'XPC_SERVICE_NAME': '0', 06:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 06:15:58 INFO - mozversion application_buildid: 20161115032714 06:15:58 INFO - mozversion application_changeset: e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 06:15:58 INFO - mozversion application_display_name: Nightly 06:15:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:58 INFO - mozversion application_name: Firefox 06:15:58 INFO - mozversion application_remotingname: firefox 06:15:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:58 INFO - mozversion application_vendor: Mozilla 06:15:58 INFO - mozversion application_version: 53.0a1 06:15:58 INFO - mozversion platform_buildid: 20161115032714 06:15:58 INFO - mozversion platform_changeset: e16d1a881481d7d83493481d9cc2c4ef1e6b9a36 06:15:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:58 INFO - mozversion platform_version: 53.0a1 06:15:58 INFO - using testdate: 1479219358 06:15:58 INFO - actual date: 1479219358 06:15:58 INFO - starting webserver on 'localhost:49241' 06:15:58 INFO - SUITE-START | Running 2 tests 06:15:58 INFO - TEST-START | dromaeo_css 06:15:58 INFO - operating with platform_type : mac_ 06:15:58 INFO - Initialising browser for dromaeo_css test... 06:15:58 INFO - TEST-INFO | started process 1627 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpFQq8L8/profile http://localhost:49241/getInfo.html) 06:16:00 INFO - PROCESS | 1627 | __metrics Screen width/height:1600/1200 06:16:00 INFO - PROCESS | 1627 | colorDepth:24 06:16:00 INFO - PROCESS | 1627 | Browser inner width/height: 1024/689 06:16:00 INFO - PROCESS | 1627 | __metrics 06:16:00 INFO - PROCESS | 1627 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:16:00 INFO - PROCESS | 1627 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:16:01 INFO - TEST-INFO | 1627: exit 0 06:16:01 INFO - Browser initialized. 06:16:01 INFO - Running cycle 1/1 for dromaeo_css test... 06:16:01 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpFQq8L8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:13 INFO - PROCESS | 1628 | RSS: Main: 238166016 06:16:13 INFO - PROCESS | 1628 | 06:17:04 INFO - PROCESS | 1628 | 1479219424405 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] 06:19:17 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 06:19:21 INFO - PROCESS | 1628 | RSS: Main: 491859968 06:19:21 INFO - PROCESS | 1628 | 06:22:24 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 06:22:24 INFO - PROCESS | 1628 | RSS: Main: 387276800 06:22:24 INFO - PROCESS | 1628 | 06:25:26 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 06:25:27 INFO - PROCESS | 1628 | RSS: Main: 338665472 06:25:27 INFO - PROCESS | 1628 | 06:28:30 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 06:28:30 INFO - PROCESS | 1628 | RSS: Main: 345919488 06:28:30 INFO - PROCESS | 1628 | 06:31:34 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 06:31:34 INFO - PROCESS | 1628 | RSS: Main: 334467072 06:31:34 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 06:34:38 INFO - PROCESS | 1628 | RSS: Main: 545533952 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | __start_tp_report 06:34:38 INFO - PROCESS | 1628 | _x_x_mozilla_page_load 06:34:38 INFO - PROCESS | 1628 | _x_x_mozilla_page_load_details 06:34:38 INFO - PROCESS | 1628 | |i|pagename|runs| 06:34:38 INFO - PROCESS | 1628 | |0;dojo.html;3337.5828021497314;3342.0475164578097;3354.1279267390455;3382.120648631355;3389.5254664346994;5652.123920792284;5709.400512946143;5791.247137872076;5847.561432892534;5865.736041878115;5729.942700572993;5752.424757524245;5889.02831032879;5891.351951285355;5909.940900591004;5095.261187127864;5157.252198431225;5178.223266510016;5233.86915327118;5266.420693723688;5289.127293996506;5338.973305133469;5342.412334643205;5345.198220266952;5358.766574406234;5163;5324.680519168863;5333.493318134771;5400.081986062359;5402.9729851350685;5178.896422071575;5252.842414727565;5261.631685781997;5264.15773476242;5271.446498117681;4344.869653910388;5180.041692286902;5211.9218211726475;5230.869228269324;5245.055889939809;5739.483446489794;5751;5754.223179870705;5766.855828604276;5767.250257466544;6164.352742962014;6182.165407669952;6183.196184495987;6192.907106393408;6204.069389591597;6191.752329906798;6195.380461953813;6235.501159907242;6274.152989346426;6276.686165691697;5773.480386765212;5791.131330300446;5791.189233507313;5792.7972520961985;5802.071668533014;6209.285932117864;6258.687065646699;6262.780802671885;6292.244930608264;6294.181756371642;6186.938130618726;6194.0708893665405;6196.597221180609;6197.907031394533;6203.286622038433;6103.640733310809;6160.661163635953;6163.291221509494;6171.9691401542705;6269.811905642838;6183.783567575113;6241.407066328691;6261.9373806262265;6270.216222972129;6278.529110316745;6265.624062556261;6268.4671802896355;6282.905756413657;6297.370262973666;6308.936910630835;4776.116418462596;4847.151748444078;4889.584385327216;4968.875778105504;5001.674891132065;4343.087951530151;4350.651947844165;4370.366296886934;4373.2565463871215;4392.363107349416;5708.600397972102;5718.285214348207;5718.914216286759;5784.768609255667;5790.073588225863;6261.843453913689;6262.342454042351;6263.812085637438;6289.591176573508;6291.937080629226;5559.082751345983;5658.971705141448;5659.773609055592;5684.147377893349;5685.573581981285;5387.9461205388225;5392.245085688007;5402.135658294654;5410.242566040757;5452.0731475648445;6328.810135695937;6331.493480521455;6338.302786693368;6340.714667839866;6342.080398342307;6139.308154112039;6205.162303089025;6208.534359923115;6290.433860952537;6294.181756371642;6229.96885015572;6245.594036387621;6259.029850373199;6317.241930968313;6349.3650634937985;6122.204113465221;6201.937980620136;6204.720787564661;6212.844678883064;6220.346863579259;6109.222480666275;6154.692265386623;6258.43674069336;6270.905936411049;6287.811365659038;4427.712198707074;5013.298138260573;6103.428585615297;6110.528382738247;6120.296165940974;6103.671041328402;6107.358727333566;6119.541034422525;6129.478994285536;6130.816075517741;5889.352171261072;5917.319508256605;5930.7924222652;5942.138389933349;5944.227250457413;6094.8171554853425;6118.632882027093;6120.479759220516;6125.1424800527075;6130.01919692847;5873.442023007808;5894.204282421818;5901.881962360643;5906.793262235801;5908.822735317947 06:34:38 INFO - PROCESS | 1628 | |1;ext.html;479.03235464361967;489.5300511508952;492.9704217746934;525.8238490105816;529.0609168725514;32208.838955805248;37502.43746343804;39220.60779392205;39293.80353098234;39439;33804.323913521715;40522.59477405225;40940;41017.794911025405;41041;9264.351495395342;9433.103855133751;9438.056194380575;9442.716718498456;9451.29115316354;9323.766905827375;9424.246060315158;9424.670136545223;9438.009009054054;9455.763605909873;9404.576794044251;9441.669541566047;9448.385854919408;9465.526723663823;9482.573284202228;9333.719988400358;9415.964243933162;9427.811443771086;9428.764280892998;9450.905490945072;9305.302102342352;9378.109079637423;9378.9531052345;9384.155426011692;9392.29557783169;38373;38598.614013859784;38725.41911871324;38731;38738.806305968574;3124.42198193335;3251.398491279121;3315.9385716415827;3389.440742277521;3514.4376899695926;28868.855655721796;29903.850480747675;30011.099667009807;30254.848725756452;30695.846520767478;3143.2927591292005;3184.458642030837;3186.3627274545183;3200.3119329344304;3286.375588638151;497679;499010;500707;500718.99999999633;500880;32473.35053298968;56259.43740562624;56589;56656.15015774767;56759.71620141873;33461.3307733844;34245.82877085599;34434.655653443646;34531;34559.4816077759;13008;14015.719685606231;14158.787618185735;14166.716665666836;14189.503367382298;11885.28688278706;11984.101192410604;11996.100292478099;11997;12005.87994120065;6667.333166708323;7636.365453918389;7669.616519174019;7728.652210650422;7744.961275193701;3208.9358212835614;3255.8046517209045;3267.134209434502;3275.1812046988252;3298.455754800479;774.3611520495614;942;951.0869565217469;987.985971196168;1045.2526443592867;28631.427371452453;29164.50025996894;29601.555976660366;29618.407631847243;29798.851005744833;997.4888044612418;1075.3601607043797;1076.962306319275;1093.3603841752504;1202.7474230411538;13253.933730331286;15120.017198882037;15354.232288385536;15515.379384824706;15522.5343239703;540132;599272;617375;624954;625855.8707206434;39559;39693.60306396957;39750.60249397527;39934.400983985266;39957.60042399597;1138.8918052784973;1284.5760898903338;1335.7662409078373;1345.845227798796;1355.0920883008212;26974.921003159652;27865.58201627018;28686.856565717037;29444.263893402836;29800.254993625334;45540.2581561142;49361;49440.25839612337;49474.763130922016;49535.752321239604;513939;515279.4236028796;517662;518319;519387;23082.653760193276;25110.995560177387;25299.873500633115;25972.35069123287;26268.60597091007;44181.55818441774;44515.77742111269;45051.32423013592;45410.54589454063;45760;35567.82216088903;37364.50541978399;37463;38403.807980959915;39148.608513915635;34897;37200.62799371972;38054.80972595119;38159.809200953816;38389.848304550906;31762.205944851565;32403.51394729034;32409.675903240666;33202.335953280315;36747.44878826766;26555.468890622466;29376.7062329374;33001;33718.66281337155;34626.48060279148;22912.770872291065;24322;24359.634605480576;24995.875020625506;25467.490650187265 06:34:38 INFO - PROCESS | 1628 | |2;jquery.html;334.9179451034496;364.22601970812025;373.95138631977846;386.58056009229983;392.97445666031723;17792.64414711705;18468.538286542844;19443.805561944395;19447.611047779035;19654.21383144676;19239.42281731547;19380.418587442364;19624.90187549064;19763;19826.90086549569;5645.463680950304;5860.436311331294;6001.66990815505;6003.099535069731;6003.429674180946;5057.873553161164;5860.326062502824;5903.911441328383;5912.615679980788;5916.437938395846;5019.64862459628;5556.222128901957;5834.387389324123;5855.648661080349;5859.326177489579;5749.223854779593;5750.3962083981205;5806.68063256525;5813.505852002585;5814.563907706918;4824.421069471651;4985.401751789772;5073.746312684388;5091.46539613339;5094.923576146361;24203;24379.39051523726;24443.266701998968;24667.62998555023;24803.875980620163;2366.1600131953255;2409.542186984467;2460.188137914484;2682.6646669166353;2696.042904768818;9894.55474503649;14387.568372948828;14447.060941038799;14667.339969701388;15366.30851611669;1784.7947486039143;1790.1675720789895;1854.1470923375264;1867.8692491525599;1879.7086451600023;94781;119908;120148;121971;122846;10188.184945204366;10380.32527885685;10612.151027917747;10937.070349020263;11204.607838725606;11670;11972.341521216242;14025.509107181404;14036.31564212301;14068.155910645393;7976.880346794803;8118.715844945398;8150.184981501802;8150.51096934174;8268.214519620748;7989.760307190793;7996.520208787491;8080;8186.549739764251;8192.836143277102;4257.425247591597;4269.865067466267;4336.82652693895;4454.5990860822685;4458.910821783546;1817.3639226270964;1887.2545344588812;1891.3002189190088;1909;1940.3305859478457;809.5749731391066;853.5390888920037;886.8536691445968;905.3119629081945;927.248205006148;18949.90525047366;19802.70295945562;20028.299009534887;20134.496637584176;20490.282840100524;781.8045488627843;883.6067949762422;893.3478560650761;932.6176267730198;953.4088864903804;9702.342627903494;10497.527611257512;10516.369017858953;10575.682729518127;10706.036456718777;508818;535563;539642;561666;563288;85970;119790.80209197797;120060;121139;127339;771.6758961236294;954.1460392948272;969.2972594868804;973.3916302311055;994.4784479746;17081.40215092466;17851.821481785537;18427.723584146515;18475.630487390445;18799.154038068045;44866;53087.116013275154;54064.53870572556;54273.45726542684;55614;78951.60524197342;82030.17969820225;82105.58947205226;83852.42213667755;83880;6626.204855417381;7019.543729657556;7136.857262854817;7202.747903823341;7268.41852651776;8970.68602598932;12155.392230388623;12404;12593.292442124986;12773.8083928743;10633.8936610636;10757.892421076001;10969.725756856142;10970.067544258509;11183.832242516206;10408.479576021016;10566.630167944086;10735.838962415413;10905.454727263446;10913.618023369145;10800;11053.11575073976;11207.775844482901;11314.773704526027;11469.541218351173;7022.719091236293;7121.928780712334;7190.280971902768;7282.089738782653;7415;9027.954860225656;10753.301035432673;10970.177236707439;11022.118230541697;11025 06:34:38 INFO - PROCESS | 1628 | |3;mootools.html;1482.2514630111796;1507.6532397548565;1674.54787207454;1809.9909500452495;1846.3999200259912;8073.152319006501;8445.57772111395;8456.534890581015;8460;8491.23578877901;7457.25427457254;7679.577623230734;7680.001599792034;7973.720919767809;8234.629441675124;2253.1212826997444;2291.2209848651455;2295.0704964489323;2295.82781291403;2296.081567373056;2294.070901284982;2334.9066037358484;2340.2979106268135;2360.079568968104;2364.3498038039506;2256.537409830989;2271.625181845002;2289.2331069091874;2291.1522736587485;2294.4263934016494;1936.3416438411007;2239.2050821958214;2265.7054582904284;2266.3880752196815;2271.954560908789;1917.2331067572943;2314.1900334882826;2314.36355002374;2317.2700599311333;2318.362450326157;8291.170882911721;8295.875561866576;8352.791180220482;8362.996440427187;8395.95802020992;5076.974615126937;5241.711705856176;5247.1866860636665;5247.317848679647;5251.264822924793;3399.201187720884;3403.6341285390326;3477.913052173716;3598.604153543108;3619.2037751694584;937.4188003437844;1085.8751243607007;1095.1020163465962;1095.775366049958;1107.5293000474846;10801.945990270076;10852.945735271272;10977.725556861142;10980.176486763525;10981.286216395909;4436.534163912807;4566.45202575693;4665.930011049837;4681.7190968542;4685.367475390847;9401.341906066646;9409.90590094104;9413.67052153185;9420.95289523548;9442.30894195151;5149.48505149482;5152.948470515322;5153.2270159476375;5153.613478989091;5164;3787.6401741834484;4155.168966206771;4684.110019096361;4694.976525117352;4723.173444647173;3137.2470607054142;3488.633693462201;3702.7593206442034;3760.6615404613717;3805.1248212911187;1225.0628269373935;1236.7798065422255;1239.9690007749878;1248.831407759959;1257.7798885195236;749.3330935467438;754.298502392769;822;840.306746933782;914.1041779056429;5289.3652761668845;5423.945760542423;5424.972875135599;5633.183188437653;5699.401562835843;910.9908900911039;926.77294062955;930.2511478260066;942.4580866002076;982.6658935961807;3601;3629.891103266906;3728.7762734236035;3742.607026262258;3778.6747662057765;9568;10026.799464010679;10051;10167.847482287772;10308.896911030944;9885.703428897144;10138.391696498235;10211.081002709792;10216.540255688511;10259.7435064124;7709.267619576131;7875.960620196977;7876;7891.921080789119;7908.248716371935;5374.677519348852;5378.784848606012;5470.343558772814;5476.91784623239;5524.558035357242;8960.32797540174;9220.538973051454;9390.248780097712;9523.476208808583;9546.284028698014;9309.534523273946;9346.906530934604;9490.762730931781;9502;9516.85724714116;6284.371562843679;7161.892571611325;7361.595112268877;7675.654595543102;7837.177096405024;7834.608269586612;7935.246151615818;7970.721024764105;8022.59887005659;8025.9598702006115;7001.684924178322;7061.929380706332;7074.504784665024;7144.071270734771;7298.051253337245;6818.147731533559;6858.211305699909;6975.093237879043;6984.685689143899;7106.538075025107;6368.395002474757;6436.935630643634;6518.543701940818;6562.475001999733;6571.934280657132;4667.649926255476;4681.485036645898;4701.459332176843;4765.642576806684;4888.633352498506;4252.319628859368;4716.528347165256;4843.46721860588;4922.630802689884;4943.3573635427165 06:34:38 INFO - PROCESS | 1628 | |4;prototype.html;272.52036415908003;280.7585476490219;301.73447366317635;307.14919672507153;310.6536212123483;4158.438610787551;4666.766661666916;5188.80657448787;5196.129393418367;5291.841244762663;4371.3661519079715;4868.172410690191;5005.6746311489815;5015.924761128577;5038.4457709652;3021.969780302191;3795.639414255655;3857.4406711026886;3858.3826587745966;3998.9200215995807;4015.899602509946;4019.859304924328;4024.7182697211206;4028.925103571663;4040.898977525571;3770.4155855842387;3816.46569480273;3843.846246150151;3938.4879965604573;3965.682745380377;3124.7187753102216;3492.8253587320532;3863.5466478785893;3974.6224108709625;3974.960250397488;3188.8883889063773;3459.15250763562;3465.7227421806197;3466.7399945004227;3474.270403215328;4043.939340909889;5051.4695956924725;5088.4148322942965;5092.8472145835685;5116.976604679079;4145.233131870584;4210.578942105795;4347.826086956518;4554.726716397026;4555.2256116460285;4665.533446655308;5445.482679145475;5480.355893232015;5502.367227768817;5528.889422211573;3285.8192799396024;3515.5605549306338;3787.715921305913;3789.886303410902;3798.7150963677836;4327.956720432819;5309.946900531023;5631.436856314377;5721.427857214287;5818.352649544448;3580.5703315602295;3849.9037524062123;3865.420186971977;3870.632289932452;3873.205992771475;3487.912802179915;3750.3999360102252;3900.464697946301;3916;3927.8821635350987;4120.690948178899;4423.668224883147;4519.276915693474;4539.228331183706;4545.909081818345;4846.80612775492;5053.393592768891;5462;5498.697571633518;5505.284313039279;1061.7239517725448;1189.393409361232;1243.936434348637;1245.5017992802952;1248.9687757806128;795.1690483444814;796.2077732655929;804.7968287410359;833.8040560468285;847.3856454070749;4051;4647.767611619405;4706.623470122382;4828.686135401258;4855.32956873718;4551.931721024187;4729.597984171384;5026.52248036436;5124.359455068116;5373.650712703662;4770.713757174581;4778.617710583145;4831.183365141569;5020.615207776277;5156.788154783623;2397.2328854766306;2627.750363715444;2791.665000199989;2836.418534200484;2844.573314002916;4652.395188625457;5024.899502009939;5631.493165615032;5643.40744221859;5660.830175094754;5326.280952071421;5512.972435137799;5564.8052318169475;5592.608517403743;5596.916046259309;4888.486708895586;5090.872728181825;5181.694146104947;5528.585356098309;5657.603967722384;4863.7324947128245;4866.367372241586;5349.170878513836;5405.135328240327;5409.648372855751;4802.975985120052;5322.574194064543;5352.678839269656;5354.7590358434745;5355.758990845499;5579.553635709215;5583.832485025456;5599.440055994368;5626.465485778845;5660.490555849993;3349.153219763279;3449.9827500862334;3505.614382417881;3562.839672214705;3569.964300356963;4985.4266759323145;5094.592432605456;5201.375834899836;5575.553955683455;5635.323761148688;4932.161532539405;5430.945690543202;5454.836354909359;5509.146082357241;5534.308211473566;4183.958160418479;5406.918896216638;5502.211781171055;5509.449055094618;5510.338759348904;5214.713190774544;5432.538234250133;5445.40100588927;5456.754446050017;5509.559235261249;4774.854035031637;5359.356877174764;5410.97294513525;5445.972770136124;5467.976848566056;4582.839600613963;4746.881327966828;5397.86505337354;5406.648567843077;5438.510534051954 06:34:38 INFO - PROCESS | 1628 | |5;yui.html;648.7761722205838;743.2864450127873;766.8427972265686;782.5239629320288;791.588374045496;13476.66308342292;14561.199134047623;14627.049241799275;14827.258637068131;14831.480898168566;12511.436985335658;12645.747085058294;13102.80345794814;13319;13488.190708557493;797.9361651067929;817.1420008990566;819.0908092017855;819.7704642700054;820.1183727492939;643.4594940250189;659.947204223661;660.8612191439779;812.4353574265888;816.677412422093;846.7332790171107;869.447900583128;870.3863776037878;882.6778225947515;884.3279107878027;871.0157522000131;871.1419252036738;872.1670804381832;872.1714371347182;874.6501399440211;501.6237821633775;643.9953672271272;644.2011905237472;644.8387903024244;648.6173157836898;11612.883871161264;11772.823407648892;12139;12163.148579599432;12173;12972.286524241163;13385.330733463288;13746.381412836447;13811.585652430444;13814.792778108336;9477.194438472738;11405.486753096131;11447.656570302905;11644.359560224184;11740.823887641693;3626.2384899171207;3678.7240956928335;3686.4286035664513;3693.7968411737343;3786.4888240087785;85571;86891.56554217188;86967.56516217378;91406;92096.53951730198;10490.42302673345;10508.762294672755;10577.100946419641;10743.946280268705;10820;17282.913585431994;19091;19675.8032419674;19746.30887918916;20512.58974820495;7588.165301816795;7662.961685191538;7792.103908050533;7889.526628402315;7963.0842453117475;4280.036991676848;4301.655867530652;4332.653387728974;4342.5006124296115;4514.548545145525;3255;3317.950230746541;3361.5293858859777;3366.360391525602;3372.224388390684;604.5616927727363;606.1998162425645;609.5519792952176;621.4003486635328;626.4017862940881;568.9943100568942;656.6421300391298;656.967151642416;663.7676813115468;669.5447095974783;8491.872621910676;8532.658693652309;8853.343438523681;9122.407043542149;9454.763630909283;607.8115784106852;656.9737210511526;671.1040760584576;683.6581709145428;684.9724814548891;4214.30463973441;4331.7834108294455;4376.737395756265;4381.78091095444;4523.547645235516;85330;104787.42818857724;106040.46979765051;108160.4591977035;109590.45204774085;87881.56059219663;90109.09890901139;90306;92876.53561732284;93546.53226733823;80630.59684701657;84303.5784821072;85125.57437212774;86044.13955860486;86659.10147521173;7467.701291948261;8578.18507241811;8630.697925572575;8683.782905427415;8859.29125669932;52570.474295258085;53436.46563534315;53833.730831345594;54400.72799635977;55210.447895520534;57791;69587;70541.29458705347;72234;79396.60301698455;12381.251589545422;12457.688557786129;12667;12825.679358016125;13097.738045239232;13750.365042988467;13907.165570065828;13929.442822286996;13945.02384833052;14371.353289102224;12929.353532323157;13105;13250.536231231861;13335.866641333463;13658.453661853415;12916.41876115596;13263.469461221828;13349.66625834362;13475.3936072875;13662.462628942727;12636;12747.426365813746;12749.298788566717;13274.535391261647;13297.135686180367;10910.290831095954;10916.672499825017;10924.235303528994;11085.22403431752;11196.888031119584;8255.587220639065;10852.620158294421;11191.2725672831;11203.60787372438;11267.26762760418 06:34:38 INFO - PROCESS | 1628 | __end_tp_report 06:34:38 INFO - PROCESS | 1628 | __start_cc_report 06:34:38 INFO - PROCESS | 1628 | _x_x_mozilla_cycle_collect,3261 06:34:38 INFO - PROCESS | 1628 | __end_cc_report 06:34:38 INFO - PROCESS | 1628 | __startTimestamp1479220478510__endTimestamp 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | ------- Summary: start ------- 06:34:38 INFO - PROCESS | 1628 | Number of tests: 6 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#0] dojo.html Cycles:165 Average:5740.56 Median:5909.94 stddev:655.36 (11.1%) stddev-sans-first:629.68 06:34:38 INFO - PROCESS | 1628 | Values: 3337.6 3342.0 3354.1 3382.1 3389.5 5652.1 5709.4 5791.2 5847.6 5865.7 5729.9 5752.4 5889.0 5891.4 5909.9 5095.3 5157.3 5178.2 5233.9 5266.4 5289.1 5339.0 5342.4 5345.2 5358.8 5163.0 5324.7 5333.5 5400.1 5403.0 5178.9 5252.8 5261.6 5264.2 5271.4 4344.9 5180.0 5211.9 5230.9 5245.1 5739.5 5751.0 5754.2 5766.9 5767.3 6164.4 6182.2 6183.2 6192.9 6204.1 6191.8 6195.4 6235.5 6274.2 6276.7 5773.5 5791.1 5791.2 5792.8 5802.1 6209.3 6258.7 6262.8 6292.2 6294.2 6186.9 6194.1 6196.6 6197.9 6203.3 6103.6 6160.7 6163.3 6172.0 6269.8 6183.8 6241.4 6261.9 6270.2 6278.5 6265.6 6268.5 6282.9 6297.4 6308.9 4776.1 4847.2 4889.6 4968.9 5001.7 4343.1 4350.7 4370.4 4373.3 4392.4 5708.6 5718.3 5718.9 5784.8 5790.1 6261.8 6262.3 6263.8 6289.6 6291.9 5559.1 5659.0 5659.8 5684.1 5685.6 5387.9 5392.2 5402.1 5410.2 5452.1 6328.8 6331.5 6338.3 6340.7 6342.1 6139.3 6205.2 6208.5 6290.4 6294.2 6230.0 6245.6 6259.0 6317.2 6349.4 6122.2 6201.9 6204.7 6212.8 6220.3 6109.2 6154.7 6258.4 6270.9 6287.8 4427.7 5013.3 6103.4 6110.5 6120.3 6103.7 6107.4 6119.5 6129.5 6130.8 5889.4 5917.3 5930.8 5942.1 5944.2 6094.8 6118.6 6120.5 6125.1 6130.0 5873.4 5894.2 5901.9 5906.8 5908.8 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#1] ext.html Cycles:180 Average:64982.84 Median:38266.40 stddev:144939.29 (378.8%) stddev-sans-first:145264.97 06:34:38 INFO - PROCESS | 1628 | Values: 479.0 489.5 493.0 525.8 529.1 32208.8 37502.4 39220.6 39293.8 39439.0 33804.3 40522.6 40940.0 41017.8 41041.0 9264.4 9433.1 9438.1 9442.7 9451.3 9323.8 9424.2 9424.7 9438.0 9455.8 9404.6 9441.7 9448.4 9465.5 9482.6 9333.7 9416.0 9427.8 9428.8 9450.9 9305.3 9378.1 9379.0 9384.2 9392.3 38373.0 38598.6 38725.4 38731.0 38738.8 3124.4 3251.4 3315.9 3389.4 3514.4 28868.9 29903.9 30011.1 30254.8 30695.8 3143.3 3184.5 3186.4 3200.3 3286.4 497679.0 499010.0 500707.0 500719.0 500880.0 32473.4 56259.4 56589.0 56656.2 56759.7 33461.3 34245.8 34434.7 34531.0 34559.5 13008.0 14015.7 14158.8 14166.7 14189.5 11885.3 11984.1 11996.1 11997.0 12005.9 6667.3 7636.4 7669.6 7728.7 7745.0 3208.9 3255.8 3267.1 3275.2 3298.5 774.4 942.0 951.1 988.0 1045.3 28631.4 29164.5 29601.6 29618.4 29798.9 997.5 1075.4 1077.0 1093.4 1202.7 13253.9 15120.0 15354.2 15515.4 15522.5 540132.0 599272.0 617375.0 624954.0 625855.9 39559.0 39693.6 39750.6 39934.4 39957.6 1138.9 1284.6 1335.8 1345.8 1355.1 26974.9 27865.6 28686.9 29444.3 29800.3 45540.3 49361.0 49440.3 49474.8 49535.8 513939.0 515279.4 517662.0 518319.0 519387.0 23082.7 25111.0 25299.9 25972.4 26268.6 44181.6 44515.8 45051.3 45410.5 45760.0 35567.8 37364.5 37463.0 38403.8 39148.6 34897.0 37200.6 38054.8 38159.8 38389.8 31762.2 32403.5 32409.7 33202.3 36747.4 26555.5 29376.7 33001.0 33718.7 34626.5 22912.8 24322.0 24359.6 24995.9 25467.5 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#2] jquery.html Cycles:180 Average:32874.02 Median:369.09 stddev:90807.05 (24603.0%) stddev-sans-first:91028.92 06:34:38 INFO - PROCESS | 1628 | Values: 334.9 364.2 374.0 386.6 393.0 17792.6 18468.5 19443.8 19447.6 19654.2 19239.4 19380.4 19624.9 19763.0 19826.9 5645.5 5860.4 6001.7 6003.1 6003.4 5057.9 5860.3 5903.9 5912.6 5916.4 5019.6 5556.2 5834.4 5855.6 5859.3 5749.2 5750.4 5806.7 5813.5 5814.6 4824.4 4985.4 5073.7 5091.5 5094.9 24203.0 24379.4 24443.3 24667.6 24803.9 2366.2 2409.5 2460.2 2682.7 2696.0 9894.6 14387.6 14447.1 14667.3 15366.3 1784.8 1790.2 1854.1 1867.9 1879.7 94781.0 119908.0 120148.0 121971.0 122846.0 10188.2 10380.3 10612.2 10937.1 11204.6 11670.0 11972.3 14025.5 14036.3 14068.2 7976.9 8118.7 8150.2 8150.5 8268.2 7989.8 7996.5 8080.0 8186.5 8192.8 4257.4 4269.9 4336.8 4454.6 4458.9 1817.4 1887.3 1891.3 1909.0 1940.3 809.6 853.5 886.9 905.3 927.2 18949.9 19802.7 20028.3 20134.5 20490.3 781.8 883.6 893.3 932.6 953.4 9702.3 10497.5 10516.4 10575.7 10706.0 508818.0 535563.0 539642.0 561666.0 563288.0 85970.0 119790.8 120060.0 121139.0 127339.0 771.7 954.1 969.3 973.4 994.5 17081.4 17851.8 18427.7 18475.6 18799.2 44866.0 53087.1 54064.5 54273.5 55614.0 78951.6 82030.2 82105.6 83852.4 83880.0 6626.2 7019.5 7136.9 7202.7 7268.4 8970.7 12155.4 12404.0 12593.3 12773.8 10633.9 10757.9 10969.7 10970.1 11183.8 10408.5 10566.6 10735.8 10905.5 10913.6 10800.0 11053.1 11207.8 11314.8 11469.5 7022.7 7121.9 7190.3 7282.1 7415.0 9028.0 10753.3 10970.2 11022.1 11025.0 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#3] mootools.html Cycles:180 Average:5418.40 Median:5153.42 stddev:2996.72 (58.2%) stddev-sans-first:2990.53 06:34:38 INFO - PROCESS | 1628 | Values: 1482.3 1507.7 1674.5 1810.0 1846.4 8073.2 8445.6 8456.5 8460.0 8491.2 7457.3 7679.6 7680.0 7973.7 8234.6 2253.1 2291.2 2295.1 2295.8 2296.1 2294.1 2334.9 2340.3 2360.1 2364.3 2256.5 2271.6 2289.2 2291.2 2294.4 1936.3 2239.2 2265.7 2266.4 2272.0 1917.2 2314.2 2314.4 2317.3 2318.4 8291.2 8295.9 8352.8 8363.0 8396.0 5077.0 5241.7 5247.2 5247.3 5251.3 3399.2 3403.6 3477.9 3598.6 3619.2 937.4 1085.9 1095.1 1095.8 1107.5 10801.9 10852.9 10977.7 10980.2 10981.3 4436.5 4566.5 4665.9 4681.7 4685.4 9401.3 9409.9 9413.7 9421.0 9442.3 5149.5 5152.9 5153.2 5153.6 5164.0 3787.6 4155.2 4684.1 4695.0 4723.2 3137.2 3488.6 3702.8 3760.7 3805.1 1225.1 1236.8 1240.0 1248.8 1257.8 749.3 754.3 822.0 840.3 914.1 5289.4 5423.9 5425.0 5633.2 5699.4 911.0 926.8 930.3 942.5 982.7 3601.0 3629.9 3728.8 3742.6 3778.7 9568.0 10026.8 10051.0 10167.8 10308.9 9885.7 10138.4 10211.1 10216.5 10259.7 7709.3 7876.0 7876.0 7891.9 7908.2 5374.7 5378.8 5470.3 5476.9 5524.6 8960.3 9220.5 9390.2 9523.5 9546.3 9309.5 9346.9 9490.8 9502.0 9516.9 6284.4 7161.9 7361.6 7675.7 7837.2 7834.6 7935.2 7970.7 8022.6 8026.0 7001.7 7061.9 7074.5 7144.1 7298.1 6818.1 6858.2 6975.1 6984.7 7106.5 6368.4 6436.9 6518.5 6562.5 6571.9 4667.6 4681.5 4701.5 4765.6 4888.6 4252.3 4716.5 4843.5 4922.6 4943.4 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#4] prototype.html Cycles:180 Average:4337.84 Median:4838.99 stddev:1336.95 (27.6%) stddev-sans-first:1305.41 06:34:38 INFO - PROCESS | 1628 | Values: 272.5 280.8 301.7 307.1 310.7 4158.4 4666.8 5188.8 5196.1 5291.8 4371.4 4868.2 5005.7 5015.9 5038.4 3022.0 3795.6 3857.4 3858.4 3998.9 4015.9 4019.9 4024.7 4028.9 4040.9 3770.4 3816.5 3843.8 3938.5 3965.7 3124.7 3492.8 3863.5 3974.6 3975.0 3188.9 3459.2 3465.7 3466.7 3474.3 4043.9 5051.5 5088.4 5092.8 5117.0 4145.2 4210.6 4347.8 4554.7 4555.2 4665.5 5445.5 5480.4 5502.4 5528.9 3285.8 3515.6 3787.7 3789.9 3798.7 4328.0 5309.9 5631.4 5721.4 5818.4 3580.6 3849.9 3865.4 3870.6 3873.2 3487.9 3750.4 3900.5 3916.0 3927.9 4120.7 4423.7 4519.3 4539.2 4545.9 4846.8 5053.4 5462.0 5498.7 5505.3 1061.7 1189.4 1243.9 1245.5 1249.0 795.2 796.2 804.8 833.8 847.4 4051.0 4647.8 4706.6 4828.7 4855.3 4551.9 4729.6 5026.5 5124.4 5373.7 4770.7 4778.6 4831.2 5020.6 5156.8 2397.2 2627.8 2791.7 2836.4 2844.6 4652.4 5024.9 5631.5 5643.4 5660.8 5326.3 5513.0 5564.8 5592.6 5596.9 4888.5 5090.9 5181.7 5528.6 5657.6 4863.7 4866.4 5349.2 5405.1 5409.6 4803.0 5322.6 5352.7 5354.8 5355.8 5579.6 5583.8 5599.4 5626.5 5660.5 3349.2 3450.0 3505.6 3562.8 3570.0 4985.4 5094.6 5201.4 5575.6 5635.3 4932.2 5430.9 5454.8 5509.1 5534.3 4184.0 5406.9 5502.2 5509.4 5510.3 5214.7 5432.5 5445.4 5456.8 5509.6 4774.9 5359.4 5411.0 5446.0 5468.0 4582.8 4746.9 5397.9 5406.6 5438.5 06:34:38 INFO - PROCESS | 1628 | 06:34:38 INFO - PROCESS | 1628 | [#5] yui.html Cycles:180 Average:19996.45 Median:4519.05 stddev:28985.99 (641.4%) stddev-sans-first:29030.90 06:34:38 INFO - PROCESS | 1628 | Values: 648.8 743.3 766.8 782.5 791.6 13476.7 14561.2 14627.0 14827.3 14831.5 12511.4 12645.7 13102.8 13319.0 13488.2 797.9 817.1 819.1 819.8 820.1 643.5 659.9 660.9 812.4 816.7 846.7 869.4 870.4 882.7 884.3 871.0 871.1 872.2 872.2 874.7 501.6 644.0 644.2 644.8 648.6 11612.9 11772.8 12139.0 12163.1 12173.0 12972.3 13385.3 13746.4 13811.6 13814.8 9477.2 11405.5 11447.7 11644.4 11740.8 3626.2 3678.7 3686.4 3693.8 3786.5 85571.0 86891.6 86967.6 91406.0 92096.5 10490.4 10508.8 10577.1 10743.9 10820.0 17282.9 19091.0 19675.8 19746.3 20512.6 7588.2 7663.0 7792.1 7889.5 7963.1 4280.0 4301.7 4332.7 4342.5 4514.5 3255.0 3318.0 3361.5 3366.4 3372.2 604.6 606.2 609.6 621.4 626.4 569.0 656.6 657.0 663.8 669.5 8491.9 8532.7 8853.3 9122.4 9454.8 607.8 657.0 671.1 683.7 685.0 4214.3 4331.8 4376.7 4381.8 4523.5 85330.0 104787.4 106040.5 108160.5 109590.5 87881.6 90109.1 90306.0 92876.5 93546.5 80630.6 84303.6 85125.6 86044.1 86659.1 7467.7 8578.2 8630.7 8683.8 8859.3 52570.5 53436.5 53833.7 54400.7 55210.4 57791.0 69587.0 70541.3 72234.0 79396.6 12381.3 12457.7 12667.0 12825.7 13097.7 13750.4 13907.2 13929.4 13945.0 14371.4 12929.4 13105.0 13250.5 13335.9 13658.5 12916.4 13263.5 13349.7 13475.4 13662.5 12636.0 12747.4 12749.3 13274.5 13297.1 10910.3 10916.7 10924.2 11085.2 11196.9 8255.6 10852.6 11191.3 11203.6 11267.3 06:34:38 INFO - PROCESS | 1628 | -------- Summary: end -------- 06:34:38 INFO - PROCESS | 1628 | 06:34:39 INFO - TEST-INFO | 1628: exit 0 06:34:39 INFO - TEST-OK | dromaeo_css | took 1120834ms 06:34:39 INFO - TEST-START | kraken 06:34:39 INFO - operating with platform_type : mac_ 06:34:39 INFO - Initialising browser for kraken test... 06:34:39 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpaz_4U5/profile http://localhost:49241/getInfo.html) 06:34:41 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 06:34:41 INFO - PROCESS | 1639 | colorDepth:24 06:34:41 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 06:34:41 INFO - PROCESS | 1639 | __metrics 06:34:41 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:34:41 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:34:42 INFO - TEST-INFO | 1639: exit 0 06:34:42 INFO - Browser initialized. 06:34:42 INFO - Running cycle 1/1 for kraken test... 06:34:42 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpaz_4U5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:54 INFO - PROCESS | 1640 | RSS: Main: 237809664 06:34:54 INFO - PROCESS | 1640 | 06:34:57 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:58 INFO - PROCESS | 1640 | RSS: Main: 288342016 06:34:58 INFO - PROCESS | 1640 | 06:35:00 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 06:35:01 INFO - PROCESS | 1640 | RSS: Main: 290295808 06:35:01 INFO - PROCESS | 1640 | 06:35:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 06:35:05 INFO - PROCESS | 1640 | RSS: Main: 381353984 06:35:05 INFO - PROCESS | 1640 | 06:35:07 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 06:35:08 INFO - PROCESS | 1640 | RSS: Main: 282525696 06:35:08 INFO - PROCESS | 1640 | 06:35:10 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:35:10 INFO - PROCESS | 1640 | RSS: Main: 287313920 06:35:10 INFO - PROCESS | 1640 | 06:35:13 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 06:35:14 INFO - PROCESS | 1640 | RSS: Main: 329519104 06:35:14 INFO - PROCESS | 1640 | 06:35:17 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 06:35:17 INFO - PROCESS | 1640 | RSS: Main: 331120640 06:35:17 INFO - PROCESS | 1640 | 06:35:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 06:35:21 INFO - PROCESS | 1640 | RSS: Main: 330338304 06:35:21 INFO - PROCESS | 1640 | 06:35:22 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:35:23 INFO - PROCESS | 1640 | RSS: Main: 303529984 06:35:23 INFO - PROCESS | 1640 | 06:35:25 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:35:25 INFO - PROCESS | 1640 | RSS: Main: 308424704 06:35:25 INFO - PROCESS | 1640 | 06:35:28 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:28 INFO - PROCESS | 1640 | RSS: Main: 309645312 06:35:28 INFO - PROCESS | 1640 | 06:35:31 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:32 INFO - PROCESS | 1640 | RSS: Main: 327434240 06:35:32 INFO - PROCESS | 1640 | 06:35:35 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:36 INFO - PROCESS | 1640 | RSS: Main: 321212416 06:35:36 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 06:35:37 INFO - PROCESS | 1640 | RSS: Main: 365928448 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | __start_tp_report 06:35:37 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 06:35:37 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 06:35:37 INFO - PROCESS | 1640 | |i|pagename|runs| 06:35:37 INFO - PROCESS | 1640 | |0;ai-astar;113;114;114;114;113;114;113;114;110;113 06:35:37 INFO - PROCESS | 1640 | |1;audio-beat-detection;149;115;111;152;118;113;116;133;173;112 06:35:37 INFO - PROCESS | 1640 | |2;audio-dft;186;182;183;186;182;183;180;183;184;181 06:35:37 INFO - PROCESS | 1640 | |3;audio-fft;83;59;77;110;83;59;59;59;83;58 06:35:37 INFO - PROCESS | 1640 | |4;audio-oscillator;90;69;91;69;68;69;91;71;70;92 06:35:37 INFO - PROCESS | 1640 | |5;imaging-gaussian-blur;88;86;86;85;88;83;87;84;92;129 06:35:37 INFO - PROCESS | 1640 | |6;imaging-darkroom;94;94;93;93;93;94;93;94;94;92 06:35:37 INFO - PROCESS | 1640 | |7;imaging-desaturate;87;87;87;88;87;85;85;87;99;134 06:35:37 INFO - PROCESS | 1640 | |8;json-parse-financial;58;55;54;54;55;55;56;54;54;55 06:35:37 INFO - PROCESS | 1640 | |9;json-stringify-tinderbox;65;66;79;74;74;69;70;71;72;71 06:35:37 INFO - PROCESS | 1640 | |10;stanford-crypto-aes;87;88;86;90;87;90;87;90;85;89 06:35:37 INFO - PROCESS | 1640 | |11;stanford-crypto-ccm;149;149;144;147;145;149;147;146;187;160 06:35:37 INFO - PROCESS | 1640 | |12;stanford-crypto-pbkdf2;175;176;173;173;175;175;173;177;172;174 06:35:37 INFO - PROCESS | 1640 | |13;stanford-crypto-sha256-iterative;67;68;68;69;67;68;66;66;67;67 06:35:37 INFO - PROCESS | 1640 | __end_tp_report 06:35:37 INFO - PROCESS | 1640 | __start_cc_report 06:35:37 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,2003 06:35:37 INFO - PROCESS | 1640 | __end_cc_report 06:35:37 INFO - PROCESS | 1640 | __startTimestamp1479220537986__endTimestamp 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | ------- Summary: start ------- 06:35:37 INFO - PROCESS | 1640 | Number of tests: 14 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.30 06:35:37 INFO - PROCESS | 1640 | Values: 113.0 114.0 114.0 114.0 113.0 114.0 113.0 114.0 110.0 113.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:125.50 stddev:21.69 (17.3%) stddev-sans-first:21.79 06:35:37 INFO - PROCESS | 1640 | Values: 149.0 115.0 111.0 152.0 118.0 113.0 116.0 133.0 173.0 112.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.94 (1.1%) stddev-sans-first:1.73 06:35:37 INFO - PROCESS | 1640 | Values: 186.0 182.0 183.0 186.0 182.0 183.0 180.0 183.0 184.0 181.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#3] audio-fft Cycles:10 Average:73.00 Median:68.00 stddev:17.30 (25.4%) stddev-sans-first:17.97 06:35:37 INFO - PROCESS | 1640 | Values: 83.0 59.0 77.0 110.0 83.0 59.0 59.0 59.0 83.0 58.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:80.50 stddev:11.22 (13.9%) stddev-sans-first:11.03 06:35:37 INFO - PROCESS | 1640 | Values: 90.0 69.0 91.0 69.0 68.0 69.0 91.0 71.0 70.0 92.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.50 stddev:13.65 (15.8%) stddev-sans-first:14.44 06:35:37 INFO - PROCESS | 1640 | Values: 88.0 86.0 86.0 85.0 88.0 83.0 87.0 84.0 92.0 129.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 06:35:37 INFO - PROCESS | 1640 | Values: 94.0 94.0 93.0 93.0 93.0 94.0 93.0 94.0 94.0 92.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:87.00 stddev:15.09 (17.3%) stddev-sans-first:15.86 06:35:37 INFO - PROCESS | 1640 | Values: 87.0 87.0 87.0 88.0 87.0 85.0 85.0 87.0 99.0 134.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.25 (2.3%) stddev-sans-first:0.71 06:35:37 INFO - PROCESS | 1640 | Values: 58.0 55.0 54.0 54.0 55.0 55.0 56.0 54.0 54.0 55.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:4.07 (5.7%) stddev-sans-first:3.67 06:35:37 INFO - PROCESS | 1640 | Values: 65.0 66.0 79.0 74.0 74.0 69.0 70.0 71.0 72.0 71.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.50 stddev:1.79 (2.0%) stddev-sans-first:1.87 06:35:37 INFO - PROCESS | 1640 | Values: 87.0 88.0 86.0 90.0 87.0 90.0 87.0 90.0 85.0 89.0 06:35:37 INFO - PROCESS | 1640 | 06:35:37 INFO - PROCESS | 1640 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:149.00 stddev:12.97 (8.7%) stddev-sans-first:13.70 06:35:38 INFO - PROCESS | 1640 | Values: 149.0 149.0 144.0 147.0 145.0 149.0 147.0 146.0 187.0 160.0 06:35:38 INFO - PROCESS | 1640 | 06:35:38 INFO - PROCESS | 1640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:175.00 stddev:1.57 (0.9%) stddev-sans-first:1.64 06:35:38 INFO - PROCESS | 1640 | Values: 175.0 176.0 173.0 173.0 175.0 175.0 173.0 177.0 172.0 174.0 06:35:38 INFO - PROCESS | 1640 | 06:35:38 INFO - PROCESS | 1640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 06:35:38 INFO - PROCESS | 1640 | Values: 67.0 68.0 68.0 69.0 67.0 68.0 66.0 66.0 67.0 67.0 06:35:38 INFO - PROCESS | 1640 | -------- Summary: end -------- 06:35:38 INFO - PROCESS | 1640 | 06:35:38 INFO - TEST-INFO | 1640: exit 0 06:35:38 INFO - TEST-OK | kraken | took 59808ms 06:35:38 INFO - SUITE-END | took 1180s 06:35:39 INFO - Completed test suite (00:19:40) 06:35:39 INFO - javascript benchmark 06:35:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.5828021497314, 3342.0475164578097, 3354.1279267390455, 3382.120648631355, 3389.5254664346994, 5652.123920792284, 5709.400512946143, 5791.247137872076, 5847.561432892534, 5865.736041878115, 5729.942700572993, 5752.424757524245, 5889.02831032879, 5891.351951285355, 5909.940900591004, 5095.261187127864, 5157.252198431225, 5178.223266510016, 5233.86915327118, 5266.420693723688, 5289.127293996506, 5338.973305133469, 5342.412334643205, 5345.198220266952, 5358.766574406234, 5163.0, 5324.680519168863, 5333.493318134771, 5400.081986062359, 5402.9729851350685, 5178.896422071575, 5252.842414727565, 5261.631685781997, 5264.15773476242, 5271.446498117681, 4344.869653910388, 5180.041692286902, 5211.9218211726475, 5230.869228269324, 5245.055889939809, 5739.483446489794, 5751.0, 5754.223179870705, 5766.855828604276, 5767.250257466544, 6164.352742962014, 6182.165407669952, 6183.196184495987, 6192.907106393408, 6204.069389591597, 6191.752329906798, 6195.380461953813, 6235.501159907242, 6274.152989346426, 6276.686165691697, 5773.480386765212, 5791.131330300446, 5791.189233507313, 5792.7972520961985, 5802.071668533014, 6209.285932117864, 6258.687065646699, 6262.780802671885, 6292.244930608264, 6294.181756371642, 6186.938130618726, 6194.0708893665405, 6196.597221180609, 6197.907031394533, 6203.286622038433, 6103.640733310809, 6160.661163635953, 6163.291221509494, 6171.9691401542705, 6269.811905642838, 6183.783567575113, 6241.407066328691, 6261.9373806262265, 6270.216222972129, 6278.529110316745, 6265.624062556261, 6268.4671802896355, 6282.905756413657, 6297.370262973666, 6308.936910630835, 4776.116418462596, 4847.151748444078, 4889.584385327216, 4968.875778105504, 5001.674891132065, 4343.087951530151, 4350.651947844165, 4370.366296886934, 4373.2565463871215, 4392.363107349416, 5708.600397972102, 5718.285214348207, 5718.914216286759, 5784.768609255667, 5790.073588225863, 6261.843453913689, 6262.342454042351, 6263.812085637438, 6289.591176573508, 6291.937080629226, 5559.082751345983, 5658.971705141448, 5659.773609055592, 5684.147377893349, 5685.573581981285, 5387.9461205388225, 5392.245085688007, 5402.135658294654, 5410.242566040757, 5452.0731475648445, 6328.810135695937, 6331.493480521455, 6338.302786693368, 6340.714667839866, 6342.080398342307, 6139.308154112039, 6205.162303089025, 6208.534359923115, 6290.433860952537, 6294.181756371642, 6229.96885015572, 6245.594036387621, 6259.029850373199, 6317.241930968313, 6349.3650634937985, 6122.204113465221, 6201.937980620136, 6204.720787564661, 6212.844678883064, 6220.346863579259, 6109.222480666275, 6154.692265386623, 6258.43674069336, 6270.905936411049, 6287.811365659038, 4427.712198707074, 5013.298138260573, 6103.428585615297, 6110.528382738247, 6120.296165940974, 6103.671041328402, 6107.358727333566, 6119.541034422525, 6129.478994285536, 6130.816075517741, 5889.352171261072, 5917.319508256605, 5930.7924222652, 5942.138389933349, 5944.227250457413, 6094.8171554853425, 6118.632882027093, 6120.479759220516, 6125.1424800527075, 6130.01919692847, 5873.442023007808, 5894.204282421818, 5901.881962360643, 5906.793262235801, 5908.822735317947], "value": 5698.324859973632, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.03235464361967, 489.5300511508952, 492.9704217746934, 525.8238490105816, 529.0609168725514, 32208.838955805248, 37502.43746343804, 39220.60779392205, 39293.80353098234, 39439.0, 33804.323913521715, 40522.59477405225, 40940.0, 41017.794911025405, 41041.0, 9264.351495395342, 9433.103855133751, 9438.056194380575, 9442.716718498456, 9451.29115316354, 9323.766905827375, 9424.246060315158, 9424.670136545223, 9438.009009054054, 9455.763605909873, 9404.576794044251, 9441.669541566047, 9448.385854919408, 9465.526723663823, 9482.573284202228, 9333.719988400358, 9415.964243933162, 9427.811443771086, 9428.764280892998, 9450.905490945072, 9305.302102342352, 9378.109079637423, 9378.9531052345, 9384.155426011692, 9392.29557783169, 38373.0, 38598.614013859784, 38725.41911871324, 38731.0, 38738.806305968574, 3124.42198193335, 3251.398491279121, 3315.9385716415827, 3389.440742277521, 3514.4376899695926, 28868.855655721796, 29903.850480747675, 30011.099667009807, 30254.848725756452, 30695.846520767478, 3143.2927591292005, 3184.458642030837, 3186.3627274545183, 3200.3119329344304, 3286.375588638151, 497679.0, 499010.0, 500707.0, 500718.99999999633, 500880.0, 32473.35053298968, 56259.43740562624, 56589.0, 56656.15015774767, 56759.71620141873, 33461.3307733844, 34245.82877085599, 34434.655653443646, 34531.0, 34559.4816077759, 13008.0, 14015.719685606231, 14158.787618185735, 14166.716665666836, 14189.503367382298, 11885.28688278706, 11984.101192410604, 11996.100292478099, 11997.0, 12005.87994120065, 6667.333166708323, 7636.365453918389, 7669.616519174019, 7728.652210650422, 7744.961275193701, 3208.9358212835614, 3255.8046517209045, 3267.134209434502, 3275.1812046988252, 3298.455754800479, 774.3611520495614, 942.0, 951.0869565217469, 987.985971196168, 1045.2526443592867, 28631.427371452453, 29164.50025996894, 29601.555976660366, 29618.407631847243, 29798.851005744833, 997.4888044612418, 1075.3601607043797, 1076.962306319275, 1093.3603841752504, 1202.7474230411538, 13253.933730331286, 15120.017198882037, 15354.232288385536, 15515.379384824706, 15522.5343239703, 540132.0, 599272.0, 617375.0, 624954.0, 625855.8707206434, 39559.0, 39693.60306396957, 39750.60249397527, 39934.400983985266, 39957.60042399597, 1138.8918052784973, 1284.5760898903338, 1335.7662409078373, 1345.845227798796, 1355.0920883008212, 26974.921003159652, 27865.58201627018, 28686.856565717037, 29444.263893402836, 29800.254993625334, 45540.2581561142, 49361.0, 49440.25839612337, 49474.763130922016, 49535.752321239604, 513939.0, 515279.4236028796, 517662.0, 518319.0, 519387.0, 23082.653760193276, 25110.995560177387, 25299.873500633115, 25972.35069123287, 26268.60597091007, 44181.55818441774, 44515.77742111269, 45051.32423013592, 45410.54589454063, 45760.0, 35567.82216088903, 37364.50541978399, 37463.0, 38403.807980959915, 39148.608513915635, 34897.0, 37200.62799371972, 38054.80972595119, 38159.809200953816, 38389.848304550906, 31762.205944851565, 32403.51394729034, 32409.675903240666, 33202.335953280315, 36747.44878826766, 26555.468890622466, 29376.7062329374, 33001.0, 33718.66281337155, 34626.48060279148, 22912.770872291065, 24322.0, 24359.634605480576, 24995.875020625506, 25467.490650187265], "value": 17778.787844439823, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.9179451034496, 364.22601970812025, 373.95138631977846, 386.58056009229983, 392.97445666031723, 17792.64414711705, 18468.538286542844, 19443.805561944395, 19447.611047779035, 19654.21383144676, 19239.42281731547, 19380.418587442364, 19624.90187549064, 19763.0, 19826.90086549569, 5645.463680950304, 5860.436311331294, 6001.66990815505, 6003.099535069731, 6003.429674180946, 5057.873553161164, 5860.326062502824, 5903.911441328383, 5912.615679980788, 5916.437938395846, 5019.64862459628, 5556.222128901957, 5834.387389324123, 5855.648661080349, 5859.326177489579, 5749.223854779593, 5750.3962083981205, 5806.68063256525, 5813.505852002585, 5814.563907706918, 4824.421069471651, 4985.401751789772, 5073.746312684388, 5091.46539613339, 5094.923576146361, 24203.0, 24379.39051523726, 24443.266701998968, 24667.62998555023, 24803.875980620163, 2366.1600131953255, 2409.542186984467, 2460.188137914484, 2682.6646669166353, 2696.042904768818, 9894.55474503649, 14387.568372948828, 14447.060941038799, 14667.339969701388, 15366.30851611669, 1784.7947486039143, 1790.1675720789895, 1854.1470923375264, 1867.8692491525599, 1879.7086451600023, 94781.0, 119908.0, 120148.0, 121971.0, 122846.0, 10188.184945204366, 10380.32527885685, 10612.151027917747, 10937.070349020263, 11204.607838725606, 11670.0, 11972.341521216242, 14025.509107181404, 14036.31564212301, 14068.155910645393, 7976.880346794803, 8118.715844945398, 8150.184981501802, 8150.51096934174, 8268.214519620748, 7989.760307190793, 7996.520208787491, 8080.0, 8186.549739764251, 8192.836143277102, 4257.425247591597, 4269.865067466267, 4336.82652693895, 4454.5990860822685, 4458.910821783546, 1817.3639226270964, 1887.2545344588812, 1891.3002189190088, 1909.0, 1940.3305859478457, 809.5749731391066, 853.5390888920037, 886.8536691445968, 905.3119629081945, 927.248205006148, 18949.90525047366, 19802.70295945562, 20028.299009534887, 20134.496637584176, 20490.282840100524, 781.8045488627843, 883.6067949762422, 893.3478560650761, 932.6176267730198, 953.4088864903804, 9702.342627903494, 10497.527611257512, 10516.369017858953, 10575.682729518127, 10706.036456718777, 508818.0, 535563.0, 539642.0, 561666.0, 563288.0, 85970.0, 119790.80209197797, 120060.0, 121139.0, 127339.0, 771.6758961236294, 954.1460392948272, 969.2972594868804, 973.3916302311055, 994.4784479746, 17081.40215092466, 17851.821481785537, 18427.723584146515, 18475.630487390445, 18799.154038068045, 44866.0, 53087.116013275154, 54064.53870572556, 54273.45726542684, 55614.0, 78951.60524197342, 82030.17969820225, 82105.58947205226, 83852.42213667755, 83880.0, 6626.204855417381, 7019.543729657556, 7136.857262854817, 7202.747903823341, 7268.41852651776, 8970.68602598932, 12155.392230388623, 12404.0, 12593.292442124986, 12773.8083928743, 10633.8936610636, 10757.892421076001, 10969.725756856142, 10970.067544258509, 11183.832242516206, 10408.479576021016, 10566.630167944086, 10735.838962415413, 10905.454727263446, 10913.618023369145, 10800.0, 11053.11575073976, 11207.775844482901, 11314.773704526027, 11469.541218351173, 7022.719091236293, 7121.928780712334, 7190.280971902768, 7282.089738782653, 7415.0, 9027.954860225656, 10753.301035432673, 10970.177236707439, 11022.118230541697, 11025.0], "value": 9260.987783958391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.2514630111796, 1507.6532397548565, 1674.54787207454, 1809.9909500452495, 1846.3999200259912, 8073.152319006501, 8445.57772111395, 8456.534890581015, 8460.0, 8491.23578877901, 7457.25427457254, 7679.577623230734, 7680.001599792034, 7973.720919767809, 8234.629441675124, 2253.1212826997444, 2291.2209848651455, 2295.0704964489323, 2295.82781291403, 2296.081567373056, 2294.070901284982, 2334.9066037358484, 2340.2979106268135, 2360.079568968104, 2364.3498038039506, 2256.537409830989, 2271.625181845002, 2289.2331069091874, 2291.1522736587485, 2294.4263934016494, 1936.3416438411007, 2239.2050821958214, 2265.7054582904284, 2266.3880752196815, 2271.954560908789, 1917.2331067572943, 2314.1900334882826, 2314.36355002374, 2317.2700599311333, 2318.362450326157, 8291.170882911721, 8295.875561866576, 8352.791180220482, 8362.996440427187, 8395.95802020992, 5076.974615126937, 5241.711705856176, 5247.1866860636665, 5247.317848679647, 5251.264822924793, 3399.201187720884, 3403.6341285390326, 3477.913052173716, 3598.604153543108, 3619.2037751694584, 937.4188003437844, 1085.8751243607007, 1095.1020163465962, 1095.775366049958, 1107.5293000474846, 10801.945990270076, 10852.945735271272, 10977.725556861142, 10980.176486763525, 10981.286216395909, 4436.534163912807, 4566.45202575693, 4665.930011049837, 4681.7190968542, 4685.367475390847, 9401.341906066646, 9409.90590094104, 9413.67052153185, 9420.95289523548, 9442.30894195151, 5149.48505149482, 5152.948470515322, 5153.2270159476375, 5153.613478989091, 5164.0, 3787.6401741834484, 4155.168966206771, 4684.110019096361, 4694.976525117352, 4723.173444647173, 3137.2470607054142, 3488.633693462201, 3702.7593206442034, 3760.6615404613717, 3805.1248212911187, 1225.0628269373935, 1236.7798065422255, 1239.9690007749878, 1248.831407759959, 1257.7798885195236, 749.3330935467438, 754.298502392769, 822.0, 840.306746933782, 914.1041779056429, 5289.3652761668845, 5423.945760542423, 5424.972875135599, 5633.183188437653, 5699.401562835843, 910.9908900911039, 926.77294062955, 930.2511478260066, 942.4580866002076, 982.6658935961807, 3601.0, 3629.891103266906, 3728.7762734236035, 3742.607026262258, 3778.6747662057765, 9568.0, 10026.799464010679, 10051.0, 10167.847482287772, 10308.896911030944, 9885.703428897144, 10138.391696498235, 10211.081002709792, 10216.540255688511, 10259.7435064124, 7709.267619576131, 7875.960620196977, 7876.0, 7891.921080789119, 7908.248716371935, 5374.677519348852, 5378.784848606012, 5470.343558772814, 5476.91784623239, 5524.558035357242, 8960.32797540174, 9220.538973051454, 9390.248780097712, 9523.476208808583, 9546.284028698014, 9309.534523273946, 9346.906530934604, 9490.762730931781, 9502.0, 9516.85724714116, 6284.371562843679, 7161.892571611325, 7361.595112268877, 7675.654595543102, 7837.177096405024, 7834.608269586612, 7935.246151615818, 7970.721024764105, 8022.59887005659, 8025.9598702006115, 7001.684924178322, 7061.929380706332, 7074.504784665024, 7144.071270734771, 7298.051253337245, 6818.147731533559, 6858.211305699909, 6975.093237879043, 6984.685689143899, 7106.538075025107, 6368.395002474757, 6436.935630643634, 6518.543701940818, 6562.475001999733, 6571.934280657132, 4667.649926255476, 4681.485036645898, 4701.459332176843, 4765.642576806684, 4888.633352498506, 4252.319628859368, 4716.528347165256, 4843.46721860588, 4922.630802689884, 4943.3573635427165], "value": 4381.677682396361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.52036415908003, 280.7585476490219, 301.73447366317635, 307.14919672507153, 310.6536212123483, 4158.438610787551, 4666.766661666916, 5188.80657448787, 5196.129393418367, 5291.841244762663, 4371.3661519079715, 4868.172410690191, 5005.6746311489815, 5015.924761128577, 5038.4457709652, 3021.969780302191, 3795.639414255655, 3857.4406711026886, 3858.3826587745966, 3998.9200215995807, 4015.899602509946, 4019.859304924328, 4024.7182697211206, 4028.925103571663, 4040.898977525571, 3770.4155855842387, 3816.46569480273, 3843.846246150151, 3938.4879965604573, 3965.682745380377, 3124.7187753102216, 3492.8253587320532, 3863.5466478785893, 3974.6224108709625, 3974.960250397488, 3188.8883889063773, 3459.15250763562, 3465.7227421806197, 3466.7399945004227, 3474.270403215328, 4043.939340909889, 5051.4695956924725, 5088.4148322942965, 5092.8472145835685, 5116.976604679079, 4145.233131870584, 4210.578942105795, 4347.826086956518, 4554.726716397026, 4555.2256116460285, 4665.533446655308, 5445.482679145475, 5480.355893232015, 5502.367227768817, 5528.889422211573, 3285.8192799396024, 3515.5605549306338, 3787.715921305913, 3789.886303410902, 3798.7150963677836, 4327.956720432819, 5309.946900531023, 5631.436856314377, 5721.427857214287, 5818.352649544448, 3580.5703315602295, 3849.9037524062123, 3865.420186971977, 3870.632289932452, 3873.205992771475, 3487.912802179915, 3750.3999360102252, 3900.464697946301, 3916.0, 3927.8821635350987, 4120.690948178899, 4423.668224883147, 4519.276915693474, 4539.228331183706, 4545.909081818345, 4846.80612775492, 5053.393592768891, 5462.0, 5498.697571633518, 5505.284313039279, 1061.7239517725448, 1189.393409361232, 1243.936434348637, 1245.5017992802952, 1248.9687757806128, 795.1690483444814, 796.2077732655929, 804.7968287410359, 833.8040560468285, 847.3856454070749, 4051.0, 4647.767611619405, 4706.623470122382, 4828.686135401258, 4855.32956873718, 4551.931721024187, 4729.597984171384, 5026.52248036436, 5124.359455068116, 5373.650712703662, 4770.713757174581, 4778.617710583145, 4831.183365141569, 5020.615207776277, 5156.788154783623, 2397.2328854766306, 2627.750363715444, 2791.665000199989, 2836.418534200484, 2844.573314002916, 4652.395188625457, 5024.899502009939, 5631.493165615032, 5643.40744221859, 5660.830175094754, 5326.280952071421, 5512.972435137799, 5564.8052318169475, 5592.608517403743, 5596.916046259309, 4888.486708895586, 5090.872728181825, 5181.694146104947, 5528.585356098309, 5657.603967722384, 4863.7324947128245, 4866.367372241586, 5349.170878513836, 5405.135328240327, 5409.648372855751, 4802.975985120052, 5322.574194064543, 5352.678839269656, 5354.7590358434745, 5355.758990845499, 5579.553635709215, 5583.832485025456, 5599.440055994368, 5626.465485778845, 5660.490555849993, 3349.153219763279, 3449.9827500862334, 3505.614382417881, 3562.839672214705, 3569.964300356963, 4985.4266759323145, 5094.592432605456, 5201.375834899836, 5575.553955683455, 5635.323761148688, 4932.161532539405, 5430.945690543202, 5454.836354909359, 5509.146082357241, 5534.308211473566, 4183.958160418479, 5406.918896216638, 5502.211781171055, 5509.449055094618, 5510.338759348904, 5214.713190774544, 5432.538234250133, 5445.40100588927, 5456.754446050017, 5509.559235261249, 4774.854035031637, 5359.356877174764, 5410.97294513525, 5445.972770136124, 5467.976848566056, 4582.839600613963, 4746.881327966828, 5397.86505337354, 5406.648567843077, 5438.510534051954], "value": 3906.8832329001802, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.7761722205838, 743.2864450127873, 766.8427972265686, 782.5239629320288, 791.588374045496, 13476.66308342292, 14561.199134047623, 14627.049241799275, 14827.258637068131, 14831.480898168566, 12511.436985335658, 12645.747085058294, 13102.80345794814, 13319.0, 13488.190708557493, 797.9361651067929, 817.1420008990566, 819.0908092017855, 819.7704642700054, 820.1183727492939, 643.4594940250189, 659.947204223661, 660.8612191439779, 812.4353574265888, 816.677412422093, 846.7332790171107, 869.447900583128, 870.3863776037878, 882.6778225947515, 884.3279107878027, 871.0157522000131, 871.1419252036738, 872.1670804381832, 872.1714371347182, 874.6501399440211, 501.6237821633775, 643.9953672271272, 644.2011905237472, 644.8387903024244, 648.6173157836898, 11612.883871161264, 11772.823407648892, 12139.0, 12163.148579599432, 12173.0, 12972.286524241163, 13385.330733463288, 13746.381412836447, 13811.585652430444, 13814.792778108336, 9477.194438472738, 11405.486753096131, 11447.656570302905, 11644.359560224184, 11740.823887641693, 3626.2384899171207, 3678.7240956928335, 3686.4286035664513, 3693.7968411737343, 3786.4888240087785, 85571.0, 86891.56554217188, 86967.56516217378, 91406.0, 92096.53951730198, 10490.42302673345, 10508.762294672755, 10577.100946419641, 10743.946280268705, 10820.0, 17282.913585431994, 19091.0, 19675.8032419674, 19746.30887918916, 20512.58974820495, 7588.165301816795, 7662.961685191538, 7792.103908050533, 7889.526628402315, 7963.0842453117475, 4280.036991676848, 4301.655867530652, 4332.653387728974, 4342.5006124296115, 4514.548545145525, 3255.0, 3317.950230746541, 3361.5293858859777, 3366.360391525602, 3372.224388390684, 604.5616927727363, 606.1998162425645, 609.5519792952176, 621.4003486635328, 626.4017862940881, 568.9943100568942, 656.6421300391298, 656.967151642416, 663.7676813115468, 669.5447095974783, 8491.872621910676, 8532.658693652309, 8853.343438523681, 9122.407043542149, 9454.763630909283, 607.8115784106852, 656.9737210511526, 671.1040760584576, 683.6581709145428, 684.9724814548891, 4214.30463973441, 4331.7834108294455, 4376.737395756265, 4381.78091095444, 4523.547645235516, 85330.0, 104787.42818857724, 106040.46979765051, 108160.4591977035, 109590.45204774085, 87881.56059219663, 90109.09890901139, 90306.0, 92876.53561732284, 93546.53226733823, 80630.59684701657, 84303.5784821072, 85125.57437212774, 86044.13955860486, 86659.10147521173, 7467.701291948261, 8578.18507241811, 8630.697925572575, 8683.782905427415, 8859.29125669932, 52570.474295258085, 53436.46563534315, 53833.730831345594, 54400.72799635977, 55210.447895520534, 57791.0, 69587.0, 70541.29458705347, 72234.0, 79396.60301698455, 12381.251589545422, 12457.688557786129, 12667.0, 12825.679358016125, 13097.738045239232, 13750.365042988467, 13907.165570065828, 13929.442822286996, 13945.02384833052, 14371.353289102224, 12929.353532323157, 13105.0, 13250.536231231861, 13335.866641333463, 13658.453661853415, 12916.41876115596, 13263.469461221828, 13349.66625834362, 13475.3936072875, 13662.462628942727, 12636.0, 12747.426365813746, 12749.298788566717, 13274.535391261647, 13297.135686180367, 10910.290831095954, 10916.672499825017, 10924.235303528994, 11085.22403431752, 11196.888031119584, 8255.587220639065, 10852.620158294421, 11191.2725672831, 11203.60787372438, 11267.26762760418], "value": 7173.026239387651, "unit": "score"}], "name": "dromaeo_css", "value": 6975.694566830509, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 110.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 111.0, 152.0, 118.0, 113.0, 116.0, 133.0, 173.0, 112.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 183.0, 186.0, 182.0, 183.0, 180.0, 183.0, 184.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 77.0, 110.0, 83.0, 59.0, 59.0, 59.0, 83.0, 58.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 91.0, 69.0, 68.0, 69.0, 91.0, 71.0, 70.0, 92.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 85.0, 88.0, 83.0, 87.0, 84.0, 92.0, 129.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 88.0, 87.0, 85.0, 85.0, 87.0, 99.0, 134.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 54.0, 54.0, 55.0, 55.0, 56.0, 54.0, 54.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 79.0, 74.0, 74.0, 69.0, 70.0, 71.0, 72.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 90.0, 87.0, 90.0, 87.0, 90.0, 85.0, 89.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 144.0, 147.0, 145.0, 149.0, 147.0, 146.0, 187.0, 160.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 173.0, 173.0, 175.0, 175.0, 173.0, 177.0, 172.0, 174.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 69.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1461.1, "alertThreshold": 2.0}]} 06:35:39 INFO - Return code: 0 06:35:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:39 INFO - # TBPL SUCCESS # 06:35:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:35:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:35:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:35:39 INFO - 2016-11-15 06:35:39.287 screenresolution[1644:9646] starting screenresolution argv=screenresolution get 06:35:39 INFO - 2016-11-15 06:35:39.290 screenresolution[1644:9646] Display 0: 1600x1200x32@60 06:35:39 INFO - 2016-11-15 06:35:39.296 screenresolution[1645:9649] starting screenresolution argv=screenresolution list 06:35:39 INFO - Available Modes on Display 0 06:35:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:35:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:35:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:35:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:35:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:35:39 INFO - Intel Iris: 06:35:39 INFO - Chipset Model: Intel Iris 06:35:39 INFO - Type: GPU 06:35:39 INFO - Bus: Built-In 06:35:39 INFO - VRAM (Dynamic, Max): 1536 MB 06:35:39 INFO - Vendor: Intel (0x8086) 06:35:39 INFO - Device ID: 0x0a2e 06:35:39 INFO - Revision ID: 0x0009 06:35:39 INFO - Displays: 06:35:39 INFO - Display: 06:35:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:35:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:35:39 INFO - Display Serial Number: 1600x1200 60 06:35:39 INFO - Main Display: Yes 06:35:39 INFO - Mirror: Off 06:35:39 INFO - Online: Yes 06:35:39 INFO - Rotation: Supported 06:35:39 INFO - Return code: 0 06:35:39 INFO - Running post-action listener: _resource_record_post_action 06:35:39 INFO - [mozharness: 2016-11-15 14:35:39.538170Z] Finished run-tests step (success) 06:35:39 INFO - Running post-run listener: _resource_record_post_run 06:35:39 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 36819968; Write bytes: 696925696; Read time: 373; Write time: 1935 06:35:39 INFO - TinderboxPrint: CPU usage
53.6% 06:35:39 INFO - TinderboxPrint: I/O read bytes / time
36,819,968 / 373 06:35:39 INFO - TinderboxPrint: I/O write bytes / time
696,925,696 / 1,935 06:35:39 INFO - TinderboxPrint: CPU idle
2,258.0 (46.4%) 06:35:39 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 06:35:39 INFO - TinderboxPrint: CPU user
1,637.3 (33.7%) 06:35:39 INFO - TinderboxPrint: Swap in / out
435,339,264 / 0 06:35:39 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394095104; Write bytes: 387917312; Read time: 29768; Write time: 725 06:35:39 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34661376; Write bytes: 294692864; Read time: 348; Write time: 1169 06:35:40 INFO - Running post-run listener: _upload_blobber_files 06:35:40 INFO - Blob upload gear active. 06:35:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:40 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 06:35:40 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:35:40 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:35:40 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:35:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:35:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:41 INFO - (blobuploader) - INFO - Done attempting. 06:35:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:35:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:35:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:42 INFO - (blobuploader) - INFO - Done attempting. 06:35:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:44 INFO - (blobuploader) - INFO - Done attempting. 06:35:44 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:44 INFO - Return code: 0 06:35:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bbe363e598abf0545f093fa11def2eb966b51464c3138f81af534a1a250aefbaae4f7e4c9914d1836317b389014c8435e9d0956236ccf6223413e7d68015fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9be2fc0f23e9d4300b49616aaa4f9767cc499780e37428c92b8d62324ae080f55bc513f912b15210cbf78ea7488f987ef222e40fca70d0e5472e65d526d727", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9da7e722f83ab5e212f2626b49ac758a5c5e0b725ff3ed9b60eb86f09bbfb81560800aeaf73d6ab5251e0e411ead09b01e9ee94c1493c536cd8ab015e4b32344"} 06:35:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:44 INFO - Contents: 06:35:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bbe363e598abf0545f093fa11def2eb966b51464c3138f81af534a1a250aefbaae4f7e4c9914d1836317b389014c8435e9d0956236ccf6223413e7d68015fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9be2fc0f23e9d4300b49616aaa4f9767cc499780e37428c92b8d62324ae080f55bc513f912b15210cbf78ea7488f987ef222e40fca70d0e5472e65d526d727", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9da7e722f83ab5e212f2626b49ac758a5c5e0b725ff3ed9b60eb86f09bbfb81560800aeaf73d6ab5251e0e411ead09b01e9ee94c1493c536cd8ab015e4b32344"} 06:35:44 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:44 INFO - Copying logs to upload dir... 06:35:44 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.694111 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-15 06:35:44.468139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 06:35:44.471296) ========= 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.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Firefox/sha512/7bbe363e598abf0545f093fa11def2eb966b51464c3138f81af534a1a250aefbaae4f7e4c9914d1836317b389014c8435e9d0956236ccf6223413e7d68015fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9be2fc0f23e9d4300b49616aaa4f9767cc499780e37428c92b8d62324ae080f55bc513f912b15210cbf78ea7488f987ef222e40fca70d0e5472e65d526d727", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9da7e722f83ab5e212f2626b49ac758a5c5e0b725ff3ed9b60eb86f09bbfb81560800aeaf73d6ab5251e0e411ead09b01e9ee94c1493c536cd8ab015e4b32344"} build_url:https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/P-T_QFo8QmGGrAnWxZ5cxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bbe363e598abf0545f093fa11def2eb966b51464c3138f81af534a1a250aefbaae4f7e4c9914d1836317b389014c8435e9d0956236ccf6223413e7d68015fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9be2fc0f23e9d4300b49616aaa4f9767cc499780e37428c92b8d62324ae080f55bc513f912b15210cbf78ea7488f987ef222e40fca70d0e5472e65d526d727", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9da7e722f83ab5e212f2626b49ac758a5c5e0b725ff3ed9b60eb86f09bbfb81560800aeaf73d6ab5251e0e411ead09b01e9ee94c1493c536cd8ab015e4b32344"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 06:35:44.504777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:35:44.505120) ========= 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.ikpeQfF3EB/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.RdtouW1bvm/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005027 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:35:44.554099) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 06:35:44.554422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 06:35:44.554764) ========= ========= Total master_lag: 0.18 =========