builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1475673390.37 results: success (0) buildid: 20161005030211 builduid: 22f29386e03d4cf09dbcfebf284b6b90 revision: ea104eeb14cc54da9a06c3766da63f73117723a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.368988) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.369819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.370094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.408087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.408463) ========= 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.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-05 06:16:30-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.2M=0.001s 2016-10-05 06:16:30 (17.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.563544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.563872) ========= 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.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.027046 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:30.618008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 06:16:30.618369) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea104eeb14cc54da9a06c3766da63f73117723a0 --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 ea104eeb14cc54da9a06c3766da63f73117723a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-05 06:16:30,702 truncating revision to first 12 chars 2016-10-05 06:16:30,702 Setting DEBUG logging. 2016-10-05 06:16:30,702 attempt 1/10 2016-10-05 06:16:30,702 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea104eeb14cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 06:16:31,996 unpacking tar archive at: mozilla-central-ea104eeb14cc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.560953 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 06:16:32.204877) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:32.205232) ========= script_repo_revision: ea104eeb14cc54da9a06c3766da63f73117723a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:32.205639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:32.205907) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:32.224180) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-05 06:16:32.224588) ========= /tools/buildbot/bin/python 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', '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.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:16:32 INFO - MultiFileLogger online at 20161005 06:16:32 in /builds/slave/test 06:16:32 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:16:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:16:32 INFO - {'append_to_log': False, 06:16:32 INFO - 'base_work_dir': '/builds/slave/test', 06:16:32 INFO - 'blob_upload_branch': 'Firefox', 06:16:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:16:32 INFO - 'branch': 'Firefox', 06:16:32 INFO - 'buildbot_json_path': 'buildprops.json', 06:16:32 INFO - 'config_files': ('talos/mac_config.py',), 06:16:32 INFO - 'default_actions': ('clobber', 06:16:32 INFO - 'read-buildbot-config', 06:16:32 INFO - 'download-and-extract', 06:16:32 INFO - 'populate-webroot', 06:16:32 INFO - 'create-virtualenv', 06:16:32 INFO - 'install', 06:16:32 INFO - 'run-tests'), 06:16:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:16:32 INFO - 'download_minidump_stackwalk': True, 06:16:32 INFO - 'download_symbols': 'ondemand', 06:16:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:16:32 INFO - 'tooltool.py': '/tools/tooltool.py', 06:16:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:16:32 INFO - '/tools/misc-python/virtualenv.py')}, 06:16:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:16:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:16:32 INFO - 'installer_path': 'installer.exe', 06:16:32 INFO - 'log_level': 'info', 06:16:32 INFO - 'log_name': 'talos', 06:16:32 INFO - 'log_to_console': True, 06:16:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:16:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:16:32 INFO - 'opt_config_files': (), 06:16:32 INFO - 'pip_index': False, 06:16:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:16:32 INFO - 'cmd': ('bash', 06:16:32 INFO - '-c', 06:16:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:16:32 INFO - 'enabled': True, 06:16:32 INFO - 'halt_on_failure': False, 06:16:32 INFO - 'name': 'check_screen_resolution'},), 06:16:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:16:32 INFO - 'cmd': ('bash', 06:16:32 INFO - '-c', 06:16:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:16:32 INFO - 'enabled': True, 06:16:32 INFO - 'halt_on_failure': False, 06:16:32 INFO - 'name': 'check_screen_resolution'},), 06:16:32 INFO - 'run_cmd_checks_enabled': True, 06:16:32 INFO - 'sps_profile': False, 06:16:32 INFO - 'sps_profile_interval': 0, 06:16:32 INFO - 'suite': 'dromaeojs', 06:16:32 INFO - 'system_bits': '32', 06:16:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:16:32 INFO - 'title': 't-yosemite-r7-0349', 06:16:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:16:32 INFO - 'use_talos_json': True, 06:16:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:16:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:16:32 INFO - 'work_dir': 'build'} 06:16:32 INFO - [mozharness: 2016-10-05 13:16:32.420703Z] Running clobber step. 06:16:32 INFO - Running pre-action listener: _resource_record_pre_action 06:16:32 INFO - Running main action method: clobber 06:16:32 INFO - rmtree: /builds/slave/test/build 06:16:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:16:34 INFO - Running post-action listener: _resource_record_post_action 06:16:34 INFO - [mozharness: 2016-10-05 13:16:34.566302Z] Finished clobber step (success) 06:16:34 INFO - [mozharness: 2016-10-05 13:16:34.566488Z] Running read-buildbot-config step. 06:16:34 INFO - Running pre-action listener: _resource_record_pre_action 06:16:34 INFO - Running main action method: read_buildbot_config 06:16:34 INFO - Using buildbot properties: 06:16:34 INFO - { 06:16:34 INFO - "project": "", 06:16:34 INFO - "product": "firefox", 06:16:34 INFO - "script_repo_revision": "production", 06:16:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:16:34 INFO - "repository": "", 06:16:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:16:34 INFO - "buildid": "20161005030211", 06:16:34 INFO - "stage_platform": "macosx64", 06:16:34 INFO - "basedir": "/builds/slave/test", 06:16:34 INFO - "nightly_build": "True", 06:16:34 INFO - "buildnumber": 33, 06:16:34 INFO - "slavename": "t-yosemite-r7-0349", 06:16:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:16:34 INFO - "platform": "yosemite_r7", 06:16:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:16:34 INFO - "branch": "mozilla-central", 06:16:34 INFO - "repo_path": "mozilla-central", 06:16:34 INFO - "revision": "ea104eeb14cc54da9a06c3766da63f73117723a0", 06:16:34 INFO - "pgo_build": "False", 06:16:34 INFO - "builduid": "22f29386e03d4cf09dbcfebf284b6b90", 06:16:34 INFO - "slavebuilddir": "test" 06:16:34 INFO - } 06:16:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:16:34 INFO - Running post-action listener: _resource_record_post_action 06:16:34 INFO - [mozharness: 2016-10-05 13:16:34.570628Z] Finished read-buildbot-config step (success) 06:16:34 INFO - [mozharness: 2016-10-05 13:16:34.570754Z] Running download-and-extract step. 06:16:34 INFO - Running pre-action listener: _resource_record_pre_action 06:16:34 INFO - Running main action method: download_and_extract 06:16:34 INFO - mkdir: /builds/slave/test/build/tests 06:16:34 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:16:34 INFO - trying https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:16:34 INFO - Downloading https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 06:16:35 INFO - Downloaded 1199 bytes. 06:16:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:16:35 INFO - Using the following test package requirements: 06:16:35 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:16:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:16:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:16:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'jsshell-mac.zip'], 06:16:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:16:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:16:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:16:35 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:16:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:16:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:35 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:16:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:16:36 INFO - Http code: 200 06:16:36 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 06:16:36 INFO - x-amz-cf-id: CI8ckB-lWvItKFbFKKJWHEgkkun2YKbtWMJcw0kXrGt-GYrzkU5Wbw== 06:16:36 INFO - x-amz-version-id: SCAC1qKe_wo6MAx9QOfojq6UvdXoMJl9 06:16:36 INFO - Expected file size: 16477043 06:16:36 INFO - Obtained file size: 16477043 06:16:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:16:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:16:43 INFO - Http code: 200 06:16:43 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 06:16:43 INFO - x-amz-cf-id: REnP8a2MzRaTUYlPl95L5xO-lgn8tlYltZ6ZHMFnvR3jIE17aO7yMA== 06:16:43 INFO - x-amz-version-id: SCAC1qKe_wo6MAx9QOfojq6UvdXoMJl9 06:16:44 INFO - Expected file size: 16477043 06:16:44 INFO - Obtained file size: 16477043 06:16:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:16:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:16:50 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:16:51 INFO - Http code: 200 06:16:51 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 06:16:51 INFO - x-amz-cf-id: Is4LbiRKrAw8m8UJpOQckz5_0rOLb8bju5SdfIlD2yLSkxFyoy8LIg== 06:16:51 INFO - x-amz-version-id: ov.T110faDKlOcMF5cQqlSxjFZCvdcEZ 06:16:51 INFO - Expected file size: 14409006 06:16:51 INFO - Obtained file size: 14409006 06:16:52 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:16:52 INFO - trying https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 06:16:55 INFO - Downloaded 111862693 bytes. 06:16:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:16:55 INFO - mkdir: /builds/slave/test/properties 06:16:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:16:55 INFO - Writing to file /builds/slave/test/properties/build_url 06:16:55 INFO - Contents: 06:16:55 INFO - build_url:https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:16:55 INFO - Running post-action listener: _resource_record_post_action 06:16:55 INFO - Running post-action listener: set_extra_try_arguments 06:16:55 INFO - [mozharness: 2016-10-05 13:16:55.751491Z] Finished download-and-extract step (success) 06:16:55 INFO - [mozharness: 2016-10-05 13:16:55.751638Z] Running populate-webroot step. 06:16:55 INFO - Running pre-action listener: _resource_record_pre_action 06:16:55 INFO - Running main action method: populate_webroot 06:16:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:16:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:16:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:16:55 INFO - '--tppagecycles', 06:16:55 INFO - '7'], 06:16:55 INFO - 'tests': ['tsvgm']}}, 06:16:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:16:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:16:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:16:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:16:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['damp', 'tps']}, 06:16:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'tests': ['damp', 'tps']}, 06:16:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['dromaeo_dom']}, 06:16:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:16:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['basic_compositor_video']}, 06:16:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:16:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'other-e10s': {'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['a11yr', 06:16:55 INFO - 'ts_paint', 06:16:55 INFO - 'tpaint', 06:16:55 INFO - 'sessionrestore', 06:16:55 INFO - 'sessionrestore_no_auto_restore', 06:16:55 INFO - 'tabpaint']}, 06:16:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['tsvgx', 06:16:55 INFO - 'tsvgr_opacity', 06:16:55 INFO - 'tart', 06:16:55 INFO - 'tscrollx', 06:16:55 INFO - 'cart']}, 06:16:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:16:55 INFO - 'tsvgr_opacity', 06:16:55 INFO - 'tart', 06:16:55 INFO - 'tscrollx', 06:16:55 INFO - 'cart']}, 06:16:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'talos_options': ['--disable-e10s'], 06:16:55 INFO - 'tests': ['tp5o']}, 06:16:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'tests': ['tp5o']}, 06:16:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'talos_options': ['--disable-e10s', 06:16:55 INFO - '--xperf_path', 06:16:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:55 INFO - 'tests': ['tp5n']}, 06:16:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:55 INFO - 'talos_options': ['--xperf_path', 06:16:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:55 INFO - 'tests': ['tp5n']}}, 06:16:55 INFO - 'talos.zip': {'path': '', 06:16:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:16:55 INFO - Running post-action listener: _resource_record_post_action 06:16:55 INFO - [mozharness: 2016-10-05 13:16:55.770299Z] Finished populate-webroot step (success) 06:16:55 INFO - [mozharness: 2016-10-05 13:16:55.770377Z] Running create-virtualenv step. 06:16:55 INFO - Running pre-action listener: _resource_record_pre_action 06:16:55 INFO - Running main action method: create_virtualenv 06:16:55 INFO - Creating virtualenv /builds/slave/test/build/venv 06:16:55 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:16:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:16:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:16:55 INFO - Using real prefix '/tools/python27' 06:16:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:16:56 INFO - Installing distribute.............................................................................................................................................................................................done. 06:16:59 INFO - Installing pip.................done. 06:16:59 INFO - Return code: 0 06:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:16:59 INFO - Reading from file tmpfile_stdout 06:16:59 INFO - Output received: 06:16:59 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:16:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:16: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:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:16: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', 'pip>=1.5'] in /builds/slave/test/build 06:16: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 pip>=1.5 06:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:59 INFO - 'HOME': '/Users/cltbld', 06:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:59 INFO - 'LOGNAME': 'cltbld', 06:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:59 INFO - 'MOZ_NO_REMOTE': '1', 06:16:59 INFO - 'NO_EM_RESTART': '1', 06:16:59 INFO - 'PAGER': '/bin/cat', 06:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:59 INFO - 'PWD': '/builds/slave/test', 06:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:59 INFO - 'SHELL': '/bin/bash', 06:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:16:59 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:59 INFO - 'USER': 'cltbld', 06:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:59 INFO - 'XPC_FLAGS': '0x0', 06:16:59 INFO - 'XPC_SERVICE_NAME': '0', 06:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:59 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:16:59 INFO - Cleaning up... 06:16:59 INFO - Return code: 0 06:16:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:16: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:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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', '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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:16: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 psutil>=3.1.1 06:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:59 INFO - 'HOME': '/Users/cltbld', 06:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:00 INFO - 'LOGNAME': 'cltbld', 06:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:00 INFO - 'MOZ_NO_REMOTE': '1', 06:17:00 INFO - 'NO_EM_RESTART': '1', 06:17:00 INFO - 'PAGER': '/bin/cat', 06:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:00 INFO - 'PWD': '/builds/slave/test', 06:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:00 INFO - 'SHELL': '/bin/bash', 06:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:00 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:00 INFO - 'USER': 'cltbld', 06:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:00 INFO - 'XPC_FLAGS': '0x0', 06:17:00 INFO - 'XPC_SERVICE_NAME': '0', 06:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:00 INFO - Downloading/unpacking psutil>=3.1.1 06:17: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:17: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:17: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:17: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:17: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:17: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:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:17:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:17:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:17:03 INFO - Installing collected packages: psutil 06:17:03 INFO - Running setup.py install for psutil 06:17:03 INFO - building 'psutil._psutil_osx' extension 06:17:03 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:17:04 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:17:04 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:17:04 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:17:04 INFO - building 'psutil._psutil_posix' extension 06:17:04 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:17:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:17:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:17:04 INFO - ^ 06:17:04 INFO - 1 warning generated. 06:17:04 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:17:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:17:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:17:04 INFO - Successfully installed psutil 06:17:04 INFO - Cleaning up... 06:17:04 INFO - Return code: 0 06:17:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:17:04 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:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:04 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:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:04 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:04 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:17:04 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:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:04 INFO - 'HOME': '/Users/cltbld', 06:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:04 INFO - 'LOGNAME': 'cltbld', 06:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:04 INFO - 'MOZ_NO_REMOTE': '1', 06:17:04 INFO - 'NO_EM_RESTART': '1', 06:17:04 INFO - 'PAGER': '/bin/cat', 06:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:04 INFO - 'PWD': '/builds/slave/test', 06:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:04 INFO - 'SHELL': '/bin/bash', 06:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:04 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:04 INFO - 'USER': 'cltbld', 06:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:04 INFO - 'XPC_FLAGS': '0x0', 06:17:04 INFO - 'XPC_SERVICE_NAME': '0', 06:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:17: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:17: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:17: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:17: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:17: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:17: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:17:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:17:07 INFO - Installing collected packages: mozsystemmonitor 06:17:07 INFO - Running setup.py install for mozsystemmonitor 06:17:07 INFO - Successfully installed mozsystemmonitor 06:17:07 INFO - Cleaning up... 06:17:07 INFO - Return code: 0 06:17:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:17: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:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17: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:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17: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', '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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:17: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 jsonschema==2.5.1 06:17:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:07 INFO - 'HOME': '/Users/cltbld', 06:17:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:07 INFO - 'LOGNAME': 'cltbld', 06:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:07 INFO - 'MOZ_NO_REMOTE': '1', 06:17:07 INFO - 'NO_EM_RESTART': '1', 06:17:07 INFO - 'PAGER': '/bin/cat', 06:17:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:07 INFO - 'PWD': '/builds/slave/test', 06:17:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:07 INFO - 'SHELL': '/bin/bash', 06:17:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:07 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:07 INFO - 'USER': 'cltbld', 06:17:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:07 INFO - 'XPC_FLAGS': '0x0', 06:17:07 INFO - 'XPC_SERVICE_NAME': '0', 06:17:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:08 INFO - Downloading/unpacking jsonschema==2.5.1 06:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:17:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:17: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:17: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:17: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:17: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:17: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:17: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:17:10 INFO - Downloading functools32-3.2.3-2.tar.gz 06:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:17:11 INFO - warning: no files found matching '*.txt' 06:17:11 INFO - no previously-included directories found matching 'build' 06:17:11 INFO - no previously-included directories found matching 'dist' 06:17:11 INFO - no previously-included directories found matching '.git*' 06:17:11 INFO - Installing collected packages: jsonschema, functools32 06:17:11 INFO - Running setup.py install for functools32 06:17:11 INFO - warning: no files found matching '*.txt' 06:17:11 INFO - no previously-included directories found matching 'build' 06:17:11 INFO - no previously-included directories found matching 'dist' 06:17:11 INFO - no previously-included directories found matching '.git*' 06:17:11 INFO - Successfully installed jsonschema functools32 06:17:11 INFO - Cleaning up... 06:17:11 INFO - Return code: 0 06:17:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:17:11 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:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:11 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:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:11 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:11 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:17:11 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:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:11 INFO - 'HOME': '/Users/cltbld', 06:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:11 INFO - 'LOGNAME': 'cltbld', 06:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:11 INFO - 'MOZ_NO_REMOTE': '1', 06:17:11 INFO - 'NO_EM_RESTART': '1', 06:17:11 INFO - 'PAGER': '/bin/cat', 06:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:11 INFO - 'PWD': '/builds/slave/test', 06:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:11 INFO - 'SHELL': '/bin/bash', 06:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:11 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:11 INFO - 'USER': 'cltbld', 06:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:11 INFO - 'XPC_FLAGS': '0x0', 06:17:11 INFO - 'XPC_SERVICE_NAME': '0', 06:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:17:11 INFO - Cleaning up... 06:17:11 INFO - Return code: 0 06:17:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:17:11 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:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:11 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:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:11 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:11 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:17:11 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:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:11 INFO - 'HOME': '/Users/cltbld', 06:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:11 INFO - 'LOGNAME': 'cltbld', 06:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:11 INFO - 'MOZ_NO_REMOTE': '1', 06:17:11 INFO - 'NO_EM_RESTART': '1', 06:17:11 INFO - 'PAGER': '/bin/cat', 06:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:11 INFO - 'PWD': '/builds/slave/test', 06:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:11 INFO - 'SHELL': '/bin/bash', 06:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:11 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:11 INFO - 'USER': 'cltbld', 06:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:11 INFO - 'XPC_FLAGS': '0x0', 06:17:11 INFO - 'XPC_SERVICE_NAME': '0', 06:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:11 INFO - Downloading/unpacking blobuploader==1.2.4 06:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:14 INFO - Downloading blobuploader-1.2.4.tar.gz 06:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:17:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:17:14 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:17:14 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:17:14 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:17:14 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:17:14 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:17:14 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:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:17:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:17:15 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:17:15 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:17:15 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:17:15 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:17:15 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:17:15 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:17:15 INFO - Downloading docopt-0.6.1.tar.gz 06:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:17:15 INFO - Installing collected packages: blobuploader, requests, docopt 06:17:15 INFO - Running setup.py install for blobuploader 06:17:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:17:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:17:15 INFO - Running setup.py install for requests 06:17:16 INFO - Running setup.py install for docopt 06:17:16 INFO - Successfully installed blobuploader requests docopt 06:17:16 INFO - Cleaning up... 06:17:16 INFO - Return code: 0 06:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17: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:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17: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:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:16 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:16 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:17:16 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:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:16 INFO - 'HOME': '/Users/cltbld', 06:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:16 INFO - 'LOGNAME': 'cltbld', 06:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:16 INFO - 'MOZ_NO_REMOTE': '1', 06:17:16 INFO - 'NO_EM_RESTART': '1', 06:17:16 INFO - 'PAGER': '/bin/cat', 06:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:16 INFO - 'PWD': '/builds/slave/test', 06:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:16 INFO - 'SHELL': '/bin/bash', 06:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:16 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:16 INFO - 'USER': 'cltbld', 06:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:16 INFO - 'XPC_FLAGS': '0x0', 06:17:16 INFO - 'XPC_SERVICE_NAME': '0', 06:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:17:16 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-CpDMdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:17:16 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-50mtAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:17:16 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ZXTKYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-jmA45M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lw36rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-b_k63w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-NfbErg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Qxo_R5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-8VAk6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-iybhQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-roRyDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:17:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-eyTHrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:17:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-JKi6_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:17:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Qtab5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-eNhNER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:17:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-pTyKMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:17:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-WCDIcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:17:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:17:18 INFO - Running setup.py install for manifestparser 06:17:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:17:18 INFO - Running setup.py install for mozcrash 06:17:18 INFO - Running setup.py install for mozdebug 06:17:18 INFO - Running setup.py install for mozdevice 06:17:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Running setup.py install for mozfile 06:17:19 INFO - Running setup.py install for mozhttpd 06:17:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Running setup.py install for mozinfo 06:17:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Running setup.py install for mozInstall 06:17:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:17:19 INFO - Running setup.py install for mozleak 06:17:19 INFO - Running setup.py install for mozlog 06:17:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Running setup.py install for moznetwork 06:17:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Running setup.py install for mozprocess 06:17:20 INFO - Running setup.py install for mozprofile 06:17:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Running setup.py install for mozrunner 06:17:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:17:20 INFO - Running setup.py install for mozscreenshot 06:17:20 INFO - Running setup.py install for moztest 06:17:20 INFO - Running setup.py install for mozversion 06:17:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:17:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:17:21 INFO - Cleaning up... 06:17:21 INFO - Return code: 0 06:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:21 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:21 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:17:21 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:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:21 INFO - 'HOME': '/Users/cltbld', 06:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:21 INFO - 'LOGNAME': 'cltbld', 06:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:21 INFO - 'MOZ_NO_REMOTE': '1', 06:17:21 INFO - 'NO_EM_RESTART': '1', 06:17:21 INFO - 'PAGER': '/bin/cat', 06:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:21 INFO - 'PWD': '/builds/slave/test', 06:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:21 INFO - 'SHELL': '/bin/bash', 06:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:21 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:21 INFO - 'USER': 'cltbld', 06:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:21 INFO - 'XPC_FLAGS': '0x0', 06:17:21 INFO - 'XPC_SERVICE_NAME': '0', 06:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-uZvzS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:17:21 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:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dW39Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:17:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1pHe6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:17:21 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:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-l8mdeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:17:21 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:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-KUDUVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:17:21 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:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:17:21 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-fuYqBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-PT_Tva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-uXXJAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-fnaoHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-sTlyhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-scIrjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-0PVVuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-6iyPpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:17:22 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:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ztHK4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:17:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:22 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TOgVTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:23 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:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:17:23 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-_tiUF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:17:23 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:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:17:23 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-I_Exb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:17:23 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:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:17:23 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:17:23 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:17:23 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:17:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:17:23 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:17:23 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:17:23 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:17:23 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:17:23 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:17:23 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:17:25 INFO - Downloading blessings-1.6.tar.gz 06:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:17:26 INFO - Installing collected packages: blessings 06:17:26 INFO - Running setup.py install for blessings 06:17:26 INFO - Successfully installed blessings 06:17:26 INFO - Cleaning up... 06:17:26 INFO - Return code: 0 06:17:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:17:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:17:26 INFO - Reading from file tmpfile_stdout 06:17:26 INFO - Current package versions: 06:17:26 INFO - blessings == 1.6 06:17:26 INFO - blobuploader == 1.2.4 06:17:26 INFO - docopt == 0.6.1 06:17:26 INFO - functools32 == 3.2.3-2 06:17:26 INFO - jsonschema == 2.5.1 06:17:26 INFO - manifestparser == 1.1 06:17:26 INFO - mozInstall == 1.12 06:17:26 INFO - mozcrash == 0.17 06:17:26 INFO - mozdebug == 0.1 06:17:26 INFO - mozdevice == 0.48 06:17:26 INFO - mozfile == 1.2 06:17:26 INFO - mozhttpd == 0.7 06:17:26 INFO - mozinfo == 0.9 06:17:26 INFO - mozleak == 0.1 06:17:26 INFO - mozlog == 3.4 06:17:26 INFO - moznetwork == 0.27 06:17:26 INFO - mozprocess == 0.23 06:17:26 INFO - mozprofile == 0.28 06:17:26 INFO - mozrunner == 6.12 06:17:26 INFO - mozscreenshot == 0.1 06:17:26 INFO - mozsystemmonitor == 0.3 06:17:26 INFO - moztest == 0.7 06:17:26 INFO - mozversion == 1.4 06:17:26 INFO - psutil == 3.1.1 06:17:26 INFO - requests == 1.2.3 06:17:26 INFO - wsgiref == 0.1.2 06:17:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17:26 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:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:26 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:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:26 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:26 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:17:26 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:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:26 INFO - 'HOME': '/Users/cltbld', 06:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:26 INFO - 'LOGNAME': 'cltbld', 06:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:26 INFO - 'MOZ_NO_REMOTE': '1', 06:17:26 INFO - 'NO_EM_RESTART': '1', 06:17:26 INFO - 'PAGER': '/bin/cat', 06:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:26 INFO - 'PWD': '/builds/slave/test', 06:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:26 INFO - 'SHELL': '/bin/bash', 06:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:26 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:26 INFO - 'USER': 'cltbld', 06:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:26 INFO - 'XPC_FLAGS': '0x0', 06:17:26 INFO - 'XPC_SERVICE_NAME': '0', 06:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 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:17:27 INFO - Cleaning up... 06:17:27 INFO - Return code: 0 06:17:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:17:27 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:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 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:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 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 0x1045cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff670d89260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, '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.LiXKLQYzmg/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.risXsnL6t1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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:17:27 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:17:27 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:17:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:17:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:27 INFO - 'HOME': '/Users/cltbld', 06:17:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:27 INFO - 'LOGNAME': 'cltbld', 06:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:27 INFO - 'MOZ_NO_REMOTE': '1', 06:17:27 INFO - 'NO_EM_RESTART': '1', 06:17:27 INFO - 'PAGER': '/bin/cat', 06:17:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:27 INFO - 'PWD': '/builds/slave/test', 06:17:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:27 INFO - 'SHELL': '/bin/bash', 06:17:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:17:27 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:17:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:27 INFO - 'USER': 'cltbld', 06:17:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:27 INFO - 'XPC_FLAGS': '0x0', 06:17:27 INFO - 'XPC_SERVICE_NAME': '0', 06:17:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:17:27 INFO - Cleaning up... 06:17:27 INFO - Return code: 0 06:17:27 INFO - Running post-action listener: _resource_record_post_action 06:17:27 INFO - Running post-action listener: _start_resource_monitoring 06:17:27 INFO - Starting resource monitoring. 06:17:27 INFO - [mozharness: 2016-10-05 13:17:27.538643Z] Finished create-virtualenv step (success) 06:17:27 INFO - [mozharness: 2016-10-05 13:17:27.539603Z] Running install step. 06:17:27 INFO - Running pre-action listener: _resource_record_pre_action 06:17:27 INFO - Running main action method: install 06:17:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:17:27 INFO - Reading from file tmpfile_stdout 06:17:27 INFO - Detecting whether we're running mozinstall >=1.0... 06:17:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:17:27 INFO - Reading from file tmpfile_stdout 06:17:27 INFO - Output received: 06:17:27 INFO - Usage: mozinstall [options] installer 06:17:27 INFO - Options: 06:17:27 INFO - -h, --help show this help message and exit 06:17:27 INFO - -d DEST, --destination=DEST 06:17:27 INFO - Directory to install application into. [default: 06:17:27 INFO - "/builds/slave/test"] 06:17:27 INFO - --app=APP Application being installed. [default: firefox] 06:17:27 INFO - mkdir: /builds/slave/test/build/application 06:17:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:18:01 INFO - Reading from file tmpfile_stdout 06:18:01 INFO - Output received: 06:18:01 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:18:01 INFO - Running post-action listener: _resource_record_post_action 06:18:01 INFO - [mozharness: 2016-10-05 13:18:01.834892Z] Finished install step (success) 06:18:01 INFO - [mozharness: 2016-10-05 13:18:01.835028Z] Running run-tests step. 06:18:01 INFO - Running pre-action listener: _resource_record_pre_action 06:18:01 INFO - Running main action method: run_tests 06:18:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:18:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:18:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:18:01 INFO - 2016-10-05 06:18:01.849 screenresolution[1639:7437] starting screenresolution argv=screenresolution get 06:18:01 INFO - 2016-10-05 06:18:01.852 screenresolution[1639:7437] Display 0: 1600x1200x32@60 06:18:01 INFO - 2016-10-05 06:18:01.857 screenresolution[1640:7439] starting screenresolution argv=screenresolution list 06:18:01 INFO - Available Modes on Display 0 06:18:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:18:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:18:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:18:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:18:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:18:02 INFO - Intel Iris: 06:18:02 INFO - Chipset Model: Intel Iris 06:18:02 INFO - Type: GPU 06:18:02 INFO - Bus: Built-In 06:18:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:18:02 INFO - Vendor: Intel (0x8086) 06:18:02 INFO - Device ID: 0x0a2e 06:18:02 INFO - Revision ID: 0x0009 06:18:02 INFO - Displays: 06:18:02 INFO - Display: 06:18:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:18:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:18:02 INFO - Display Serial Number: 1600x1200 60 06:18:02 INFO - Main Display: Yes 06:18:02 INFO - Mirror: Off 06:18:02 INFO - Online: Yes 06:18:02 INFO - Rotation: Supported 06:18:02 INFO - Return code: 0 06:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:18:02 INFO - Python 2.7.3 06:18:02 INFO - Return code: 0 06:18:02 INFO - grabbing minidump binary from tooltool 06:18: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:18:02 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 0x1043dfd30>, '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 0x104205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:18:02 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:18:02 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:18:02 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:18:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:18:02 INFO - Return code: 0 06:18:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:18:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:18:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:18:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:18:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:18:02 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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:18:02 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --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:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LiXKLQYzmg/Render', 06:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:02 INFO - 'HOME': '/Users/cltbld', 06:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:02 INFO - 'LOGNAME': 'cltbld', 06:18:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:18:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:02 INFO - 'MOZ_NO_REMOTE': '1', 06:18:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:18:02 INFO - 'NO_EM_RESTART': '1', 06:18:02 INFO - 'PAGER': '/bin/cat', 06:18:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:02 INFO - 'PWD': '/builds/slave/test', 06:18:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:02 INFO - 'SHELL': '/bin/bash', 06:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.risXsnL6t1/Listeners', 06:18:02 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 06:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:02 INFO - 'USER': 'cltbld', 06:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:02 INFO - 'XPC_FLAGS': '0x0', 06:18:02 INFO - 'XPC_SERVICE_NAME': '0', 06:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:02 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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:18:02 INFO - mozversion application_buildid: 20161005030211 06:18:02 INFO - mozversion application_changeset: ea104eeb14cc54da9a06c3766da63f73117723a0 06:18:02 INFO - mozversion application_display_name: Nightly 06:18:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:18:02 INFO - mozversion application_name: Firefox 06:18:02 INFO - mozversion application_remotingname: firefox 06:18:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:18:02 INFO - mozversion application_vendor: Mozilla 06:18:02 INFO - mozversion application_version: 52.0a1 06:18:02 INFO - mozversion platform_buildid: 20161005030211 06:18:02 INFO - mozversion platform_changeset: ea104eeb14cc54da9a06c3766da63f73117723a0 06:18:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:18:02 INFO - mozversion platform_version: 52.0a1 06:18:02 INFO - using testdate: 1475673482 06:18:02 INFO - actual date: 1475673482 06:18:02 INFO - starting webserver on 'localhost:49275' 06:18:02 INFO - SUITE-START | Running 2 tests 06:18:02 INFO - TEST-START | dromaeo_css 06:18:02 INFO - operating with platform_type : mac_ 06:18:02 INFO - Initialising browser for dromaeo_css test... 06:18:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpR3gIc5/profile http://localhost:49275/getInfo.html) 06:18:04 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 06:18:04 INFO - PROCESS | 1650 | colorDepth:24 06:18:04 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 06:18:04 INFO - PROCESS | 1650 | __metrics 06:18:05 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:18:05 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:18:05 INFO - TEST-INFO | 1650: exit 0 06:18:05 INFO - Browser initialized. 06:18:05 INFO - Running cycle 1/1 for dromaeo_css test... 06:18:05 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpR3gIc5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:17 INFO - PROCESS | 1656 | RSS: Main: 236011520 06:18:17 INFO - PROCESS | 1656 | 06:19:08 INFO - PROCESS | 1656 | 1475673548981 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:21:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 06:21:24 INFO - PROCESS | 1656 | RSS: Main: 415416320 06:21:24 INFO - PROCESS | 1656 | 06:24:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 06:24:27 INFO - PROCESS | 1656 | RSS: Main: 373714944 06:24:27 INFO - PROCESS | 1656 | 06:27:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 06:27:30 INFO - PROCESS | 1656 | RSS: Main: 343040000 06:27:30 INFO - PROCESS | 1656 | 06:30:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:30:33 INFO - PROCESS | 1656 | RSS: Main: 336736256 06:30:33 INFO - PROCESS | 1656 | 06:33:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:33:37 INFO - PROCESS | 1656 | RSS: Main: 329469952 06:33:37 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:36:40 INFO - PROCESS | 1656 | RSS: Main: 380227584 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | __start_tp_report 06:36:40 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 06:36:40 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 06:36:40 INFO - PROCESS | 1656 | |i|pagename|runs| 06:36:40 INFO - PROCESS | 1656 | |0;dojo.html;3316.602007759069;3330.5670262865833;3335.132865454981;3344.4983252512134;3391.6608339166073;5643.746031428585;5681.23303354047;5738.3974682658345;5743.282089738783;5807.767689292434;5707.5148612368;5709.086546152616;5789.4500022497905;5832.62087964282;5885.735141918613;5148.922766158511;5190.714510701929;5238.842834714965;5250.684958902459;5266.868328291805;5346.946530534684;5362;5364.168553874136;5377.462253774611;5380.784768609251;5322.520973112439;5340.973295133538;5353.678908918627;5361.839144825642;5418.593605479585;5150.227465880111;5194.610404219661;5227.47725227478;5234.084035293847;5246.816361427332;5113.53978141969;5285.048691235576;5289.735513224324;5295.285136506599;5297.8145764898045;5526.4197259288;5616.494515493625;5618.7190640468225;5636.210930469738;5645.32256129263;6098.298695650013;6108.572399932006;6121.051237058263;6123.234595675541;6124.142620033199;4923.48303428142;6141.877162456771;6151.138840562325;6164.815055548341;6231.501479881644;5784.88430231393;5790.10253410722;5791.681457519792;5794.101914203306;5800.912986305167;6334.809955701337;6337.9683101585115;6342.778002769881;6358.364163583605;6360.554761166674;5854.882902341929;5895.02732049207;6006.699665016732;6082.9695851520455;6097.268327800603;6058.030715085653;6060.33336333003;6063.060225665029;6116.235470566179;6136.6011209271255;5862.853428664317;5948.613340132878;5958.344582095966;5958.940410595926;6023.969880150571;6094.094888519665;6145.678679084019;6173.536984726164;6294.024426213944;6327.525435592349;4906.55840974314;4950.257461380822;4961.479044700254;4980.950190498121;4996.225585034325;4428.933565996513;4432.113577284556;4432.224360736858;4447.62195213404;4471.217536931024;5680.914786278209;5745.6552606843725;5762.337331206881;5766.961946849608;5775.220345253337;6130.52043589939;6217.813465596039;6264.655443950626;6299.118123462719;6300.621962682254;5754.654720716687;5757.0788673811985;5760.3951585083805;5763.538916886639;5767.711614419262;5434.728263586884;5437.864053398696;5440.238366628675;5442.12925931849;5449.782008719686;5195.506426889439;6298.716557755004;6316.778912738032;6323.620582765049;6336.303006669171;6200.534959877937;6204.162438070803;6243.843903902439;6314.715837787402;6316.62100273985;6342.365763423621;6344.809655710336;6353.4599559038;6353.9046914295395;6355.7775477859905;6103.633781973096;6136.140940268277;6160.353162917829;6182;6210.099535567408;6210.441060304594;6232.407921247474;6233.812985610439;6281.874362512633;6308.779192728232;6030.819075427744;6168.35232300602;6192.59748116371;6198.2252218472695;6213.068039794067;6061.1514387984835;6101.572889897842;6115.53329557081;6150.531413494974;6169.81490555284;4828.275758636232;5903.645781252967;5910.408959104056;5951.226340575698;6116.663583502772;5255.474452554714;5803.738831752666;6181.752729890754;6192.59748116371;6194.256689197326;5853.590248682722;5922.2597175353085;5931.762729490944;6013.158157857991;6084.7566097357385 06:36:40 INFO - PROCESS | 1656 | |1;ext.html;474.4994031296981;493.664308270376;534.1747000883637;538.7333270031334;547.6577139287945;28850.278743031435;40554;41631;41792;44058.33912491315;42722.78638606802;43063.138737225236;43176.352354714705;43223.135537289316;43252.78373608136;9039.63841446343;9831.213502919785;9838.655647052354;9840.409575425461;9852.753681157992;9673.661421850236;9808.16630586401;9809.754756131118;9815.558299876488;9829.65596204133;9804.31369804114;9814.362066465657;9818.165455936254;9828.017198280151;9833.459159746211;9753.902460975405;9792.510374481248;9794.314397992144;9798.6080556778;9842.458664773434;9603.039696030412;9680.031996800333;9685.079917407835;9687.903120968771;9702.27232957524;40847.38728919069;41234.587654123374;41281.587184128075;41292;41410.585894140975;2887.4946884295373;3012.638483382008;3300.8019518828705;3381.4420620597816;3389.2204792897774;30861.845690771628;31346.843265783755;31766;31885;32571.34857302841;3237.1097948064235;3247.318063206729;3261.1521004538754;3280.3931272714626;3366.831658417094;499101;500273.49863250816;501475;502471;503157;60023.39976600266;60195.39804601986;60344.69827650834;60356;60478.09282860761;34736.131596710286;35449.64550354515;35506.75726349565;36421;36444.817775911484;16114.435994740363;16317.67364652724;16324.673506529803;16331.510054698378;16346.673066538602;12415;12527.060470464752;12532.373381330897;12541.059420543495;12554.497820087277;18881;19061.714074288895;19065.951372674637;19135.330263440992;19136.234550618097;7915.366770658334;7932.000079439178;7958.164392738795;8019.639116239783;8043.39674524413;890.5057692980329;963.9662611808553;972.9221662267001;973.2603221551672;1060.0353678152844;24525.63211551828;29796.33441928317;29944.850275748482;29972.400551988838;30280.091597252118;974.4202199691173;983;1054.8048611006989;1088.2382332367374;1196.407778149804;14569.854301457062;15103.848961510465;15736.921315393578;15929.964552304064;15943.68112637764;665725;666716;669127;671990;675801;58820.7058964711;59259;59272.70363648154;59287.70356148278;59341.10988335178;1252.2611659121162;1294.501616877509;1375.0580852116332;1406.5499040307209;1426.2227086238024;29285.974990875216;31278.53082203814;31638.957705280234;31715.365692685555;32054.35891282208;49061.75469122775;49577.504224958735;49704.75738106576;49714;49730;543923;546135;547357.2632136814;547588.7861682158;548632;23396.532069358178;24028.87985560131;26540;26907;27019.72980270172;45015;47079.76460117677;47914.80212994703;49544.75227623839;49886.251706225165;39146.23841927177;39184.21631567296;39262.41106383464;40089.39865902072;40834.59165408308;38681.806590966866;38742.6125738739;38810.417843731804;39484.40773388459;40201.798991004856;38203.33180741649;38614.034649133995;38655.42016869805;39347.80326098351;39451.40822887716;32471;35037.64962350446;35048;35488.46767298544;35577.64422355847;23836.761632384147;25016.999320026996;25485;25550.488990220463;26065.73934260633 06:36:40 INFO - PROCESS | 1656 | |2;jquery.html;349.4217070747913;365.77139287945033;369.33150996695963;378.98673546425874;396.9384745364468;14907.5527734168;15117.168555729444;15877.285522151502;20313.49216269594;20741.274055408063;19117.904410477928;20051.899740501278;20145.79125252482;20315.898420507878;20486.795132048675;5665.5467562594895;5948.286205655306;5959.255093113361;5962.224910761616;5964.970175149118;4942.382202224722;6664.533482656216;6715.664216789165;6741.865162696743;6754.020667003281;5638.661680299175;5957.463828255456;6675.132232809752;6684.231313398973;6686.2310834254195;5981.910271345933;6017.729202185912;6023.909641355362;6034.969825150846;6042.06348016056;5038.319826823368;5311.521963023347;5368.463153684639;5404.648697834628;5798;12709.555165569162;12751.171173873761;12798.872011279862;12882.033847461478;12927.935360323232;2092.5396412789164;2147.5060736030646;2271.136967952184;2279.1681036421587;2402.1112188490197;9393.154616084585;9574.904250957541;9747.409487984149;9977.90022099777;10140.695779126565;1747.7815273090864;1799.5321216483808;1845.2250054976942;1862.6554087493857;1871.7941026487076;107945.46027269893;126207;126612;126665;127613;11069.446527673585;11378;11393.487293071832;11396.259243149168;11631.6510504685;14089;14099.224542650047;14101.884717292489;14127;14198.929005355114;8201.466904651179;8428.157184281523;8477.194666506672;8580.871286930702;8582.742517724479;8307.875381869277;8311.210435008665;8317.043539992857;8473.957630211933;8488.193621605938;4289.420928174719;4418.02609713167;4424.336349547593;4474.37358769766;4490.932636010462;1828.4057681253646;1897.1178402043117;1898.6487499812304;1901;1903.4099429177;824.744329257932;887.1173182683272;901.3420203251663;912.3385545479474;934.6121359635811;18417.907910460362;20696.79303206979;21598.13607455684;21601.243956461763;21656.56686866254;748.3078152708831;796.6096612659755;912.5254867469012;923.8106188231396;968.8159249742527;9454.621815127455;10234.181265498743;10981.505832237568;11083.612073577386;11221.887781122085;566359;568080;576550.3517447242;587665;593282;82693.75959360614;100472.99527004783;112759;117677.41161294139;123338;936.2977766674994;940.1867384712311;981.2787601113318;983.4148681534475;984.3306551544875;19007.42977710671;19395.418137455334;19611;19627.8037219626;19643.410697679094;26282.474350513265;34606.480902786985;47032.29451558161;50741;51797.741011294704;73145.63427183042;75176;75908.62045689957;76080;76641.61679191569;7553.357964573072;7762.910100109075;7941.801454963672;7997.200279971957;8086.838263234585;8928.910710892807;11523.654290371302;11827.940860295643;12874.935625321814;13003.284819335027;5289.947100528945;8828.470291782514;10840.403777792297;11150.776984460103;11218.326900386002;10758.193135515025;10829;10903.672889813319;11289.096872250037;11323.207375483636;10806.18953578469;11150.330980141218;11276.041536469493;11343.277613372897;11372.886271137513;7720.382369410546;7824.608769561613;7850.293473587404;7893.36853051766;8022.438429309892;8040.075391330072;10524.421156836448;10663.57345706194;11039.061679757311;11088.057515111306 06:36:40 INFO - PROCESS | 1656 | |3;mootools.html;1475.52783109405;1498.8426215247398;1710.8374704403077;1809.6290260496598;1829.7283388045314;7713.4986225895345;8353.749387518368;8886.511241881693;8893.644254229823;8915.732528024162;7862.6854925803045;8112.513249205037;8484.95757521213;8751.606177722002;8771;2310.734265559457;2344.2146880794953;2345.6012477878717;2347.816530626517;2350.2949115265437;2319.5824751544715;2337.2754446121758;2343.472718638301;2366.692329997098;2367.4199821043867;2338.286822519131;2350.6356514740155;2358.3278765551822;2368.5854975379325;2373.5134297469062;2329.464223228667;2343.6015877300815;2348.377679914824;2348.5889969255313;2355.5406695694346;2286.4398222435443;2328.80205182558;2333.708286464192;2338.988305058481;2343.61330380487;7695.461317707763;8885.733427997171;8949.955250223773;8954.149355811187;8991.280697544245;5164.173732202867;5314.73426328682;5317.069512835238;5320.228566857822;5330.813421530266;3467.167879708877;3562.2697347043795;3709.4992176056157;3710;3723.7579557328686;1082.182951871332;1083.06315037492;1086.9184811139196;1106.4965440724532;1110.572429614596;11616.477258523386;11754.353510557;11764.941175294154;11770.2349347293;11794.705132371759;4143.6685065194715;4484.977575112103;4722.409698787652;4730.5032971538185;4747.074320507468;9861.654842080636;9875.802483950281;9877.11106000449;9877.753056173653;9892.406455612543;5317.8936421271355;5320.893582128336;5321.042212401728;5323.653962492425;5326.760295786621;4188.6649068074485;4231.87304380869;4394.165108629414;4884.511548845087;4889.462159162489;3575.588807287143;3659.9935017869766;3737.831797569116;3768.8680896168503;3855.9036024099623;1257.076049103908;1262.50762202741;1269.930153841529;1279.8400199975003;1280.238258236348;799.2327365728966;861.5950503263456;890.1632465482426;929.4609126706493;937.4281688170211;5814.912776308359;5943.524518038546;5954.612950158225;6113.602615829956;6352.872942541123;890.6570970176529;910.7632015675972;912.8311262416474;920.8112336971038;964.9179819715403;3685.6867166291167;3873.4964454620717;3907.9804600976813;3991.3613821788954;4031.7580945143386;10281.074703276592;10556.736081598021;10669.253152279265;10724.215280091932;10813.243072984815;10770.407627580558;10770.515326810313;10770.838437423445;10797.568097276178;10802.67591972242;7852.764417067498;8258.876116858251;8312.41813073079;8334.249917507454;8336.708215212439;5713.285839270091;5726.141078838207;5733.913991290217;5828.679422631796;5858.560607954506;9807.019298070136;10138.949305253427;10164.593416263267;10205;10226;9813.01869813013;10303.227257955834;10314.690559283234;10524.263301568817;10534.315269507457;6831.897521537082;7080.787576372717;7090.539114957512;7117.572945623279;7123.109611298588;8234.711785087493;8323.16768323187;8336.958315208385;8344.249017588447;8369.95815020921;7243.818904527429;7314.6708398121145;7392.37164840973;7557.962210188914;7645.044369453818;7278.272172782679;7381.372583330477;7406.037215161994;7418.888716669362;7521.661525231487;6933.791986240421;7102.609356485443;7107.147142342952;7107.928920710933;7110.786676399716;4887.218045112907;4961.206207006861;4966.329545511454;5004.77478513475;5101.362329708786;4888;4958.7024778513405;5016.648834581544;5068.417132029864;5168.8449346519665 06:36:40 INFO - PROCESS | 1656 | |4;prototype.html;264.8109986162132;302.2700179067552;302.5824362379914;309.61762223653784;319.9072269041978;4713.693609915357;5088.694678319294;5182.585393168547;5314.5748340132795;5331.626786124973;4717.5518325759085;4812.23004319309;5051.751970373185;5144.25408315794;5383.67697938124;3282.96717032829;4019.477467929165;4146.170765846828;4154.833806647731;4155.4832486142495;3443.6397622938925;3938.8621398251066;4156.418101465797;4169.687273454488;4174.77038762868;3436.9656303436896;4110.568390319018;4118.299889018859;4127.277726397883;4159.2721273777115;4105.507339119295;4105.5894410559;4129.690273229489;4130.855420060314;4132.979335103335;3589.982050089759;3604;3614.638536146391;3616.566012078541;3617.366960781853;4373.497047839507;4990.600751939836;5168.250603662448;5238.816641417532;5273.393559740641;4703.318018887277;4710.952890471086;4712.69367491112;4737.59730422911;4742.786574604151;5158.38099428071;5227.222744266929;5617.9438205618235;5630.662160270397;5637.746301416446;3689.8524059037604;3958.2083583283456;3967.266055779661;3974.5826688197903;3977.707245145339;5881.911771323434;5926.762929482816;5933;5936.347001829795;5988.251468566429;3752.080740218609;4033.9596604034173;4036.555978842325;4068.6745060395097;4074.9796251018556;3518.0677120563073;3771.358868992278;4103.158852435244;4112.814923328458;4126.442930204384;4480.843170489017;4701.764911754467;4707.835225767081;4726.338312636233;4743.478217396084;5662.696350773442;5669.688167150764;5672.319321681342;5696.458836410535;5697.57268204878;1215.0826126274605;1254.993725031369;1306.6406738147086;1306.843178818529;1376.8347798264272;787.322902304018;820.4666966471841;820.5363969357294;839.8530257204965;872.4634349874781;4334.826606935687;4381.342798580238;4411.448568928884;4426.911461770746;4438.423005009393;4164.3961625564125;4412.426384569985;4773.880652983703;4817.373741413594;4820.457453614809;4085.816138273785;4203.70574059819;4357.760323182255;4371.513685346934;4374.753195339313;2231.977680223177;2246.7081428178863;2305.6281512500036;2343.695319608474;2344.6401087369286;4257.891135054687;4449.974017668017;4529.343245229489;4576.359309696645;4897.755112244445;4238.642036944583;4355.390245365651;4707.388039554836;4803.231482962779;4874.805007799649;4359.760213188241;4840.951590484121;5461.42655021225;5928.288605367384;5994.610350327128;5060.190369541003;5870.735816888336;5883.499902508334;5884.411558844081;5884.852878678067;5483.616146869681;5817.738201780845;5831.533477321889;5884.352721200579;5897.469227769521;4988.476209997898;5969.7313620888035;5990.891685038107;6001.669908155093;6025.548083893638;3882.611738826095;3932.783696896698;3937.9409308859817;3953.4465174874967;3958.6833053355085;4929.482404347492;5641.082032109102;5955.553333499918;5978.103284507359;5998.520118390606;5028.446870844277;5080.060188865072;5233.476652334736;5460.453954604508;5714.571407144397;5231.372235331784;5351.491608297206;5709.457601527848;5730.770769169187;5796.304443466811;5055.595552355876;5299.576033917355;5302.859885124717;5421.473069986448;5555.916661250004;5238.345206849144;5252.448492908343;5694.943050569441;5736.5410767139365;5737.307494289278;5592.944070559242;5614.85962850932;5675.801346952837;5677;5680.801171958961 06:36:40 INFO - PROCESS | 1656 | |5;yui.html;618.5175563060812;746.1754760989108;761.5088268067096;785.1285073568338;826.6280173921737;15122.243887805607;15162.469313574027;15620.843791562082;15928.522144335673;16298.755518667229;14222.786658200135;14322.641933951656;14323.64190895228;14337.856621433808;14343.211123388235;855.9786005349854;883.7525492862009;887.1926546842375;887.2103827593447;891.7681402835244;701.1516065560678;724.5145752345915;857.6998050682242;879.1164879296298;890.2610833008588;861.3496809908511;908.9772755681128;932.3753085432743;933.2906990687093;935.4527601353217;925.2181906289175;926.6941909169958;927.5825878354734;929.5631053404888;933.6872147830485;535.4725595288637;556.210181542211;558.045741781554;558.3355806590185;559.1863838115532;12071.93964030183;14878.628034299121;14899.255037248202;15186.164760938143;15200;14289.428422863073;14647.121172729563;14728.263586820616;14823.555293341216;14945.701085978328;10332.224263793032;10537.473126343652;11756.706082347924;11813.704657383634;12044.156909016372;3578.4453409721536;3836.156045669948;3849.6727778138893;3868.883933482;3997.6601988830985;84133;84854.87862803417;102710.3780329887;106142.46928765385;110763;11179.21745477825;11276.605318813803;11334.773304533863;11553.595624153115;11703.122265830098;23569.764302356758;23571.7642823573;23704.40738981505;23763.405914851923;23837.284881453583;7752.224777522316;7800.726974555863;7857.764267071997;7871.724489642835;7966.800829979181;4221.155768846182;4246.8725938221905;4265.44294410133;4308.978455107705;4382.693211475166;3515.7187425005936;3605.7475976681903;3619.3123306571665;3641.362761516724;3644.9817750911075;648.7988723495731;651.7621068310033;654.4012228810681;657.7369052378991;666.823291827666;601.9548533859978;652.9379708927644;665.6139439125295;675.8817206988757;683.8085336105898;8818.647254109892;9187;9435.85545199958;9465;9483.430994140374;572.1960645293369;617.8980468222783;630.0706457974543;650.5381179362705;663.184283331505;3864.169203621179;4177.770222637784;4487.012857171417;4549.727016379028;4650.348951146842;96825.5158724216;112329;145782.54217457902;154784;161702.38297616874;137176;138414;141087.57252732417;143342;144160.27919860333;76521.23478765253;115963.84036159699;118762.4061879685;120713;121455.17817232749;8825.852639156868;8884.466931984101;8894.644214231359;8984.146506081912;9083.227925626396;64471.677641611495;65918.01122983255;66314.67370652656;66968.39764967514;68047.63904721977;84285;95659.56510652485;97930.51034744781;99957;101779.49110254401;11718.88281117212;11956.701082473008;12158.148929574845;12220.877791221974;12223.93888030554;10780.892191078303;12061.698457538632;12846.67883302925;12927.353632318534;13355.53255636048;12115.818262726243;12122.636320910387;12132.696682583006;12139.75720485603;12362.690932726753;12165.462198221261;12166.75666486683;12216.328101954477;12318.692032699255;12329.506819727469;10352.327098738559;11829.704257393634;12005.459754310903;12100.515979360727;12116.878831211927;10149.39103653783;10157.085862272135;10184.388936663823;10628.52171652262;10707.89292107069;10216.489175541343;10454.320469169785;10486.16110711126;10494.160467162455;10497.2127090467 06:36:40 INFO - PROCESS | 1656 | __end_tp_report 06:36:40 INFO - PROCESS | 1656 | __start_cc_report 06:36:40 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2350 06:36:40 INFO - PROCESS | 1656 | __end_cc_report 06:36:40 INFO - PROCESS | 1656 | __startTimestamp1475674600551__endTimestamp 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | ------- Summary: start ------- 06:36:40 INFO - PROCESS | 1656 | Number of tests: 6 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5724.05 Median:5885.74 stddev:627.68 (10.7%) stddev-sans-first:600.52 06:36:40 INFO - PROCESS | 1656 | Values: 3316.6 3330.6 3335.1 3344.5 3391.7 5643.7 5681.2 5738.4 5743.3 5807.8 5707.5 5709.1 5789.5 5832.6 5885.7 5148.9 5190.7 5238.8 5250.7 5266.9 5346.9 5362.0 5364.2 5377.5 5380.8 5322.5 5341.0 5353.7 5361.8 5418.6 5150.2 5194.6 5227.5 5234.1 5246.8 5113.5 5285.0 5289.7 5295.3 5297.8 5526.4 5616.5 5618.7 5636.2 5645.3 6098.3 6108.6 6121.1 6123.2 6124.1 4923.5 6141.9 6151.1 6164.8 6231.5 5784.9 5790.1 5791.7 5794.1 5800.9 6334.8 6338.0 6342.8 6358.4 6360.6 5854.9 5895.0 6006.7 6083.0 6097.3 6058.0 6060.3 6063.1 6116.2 6136.6 5862.9 5948.6 5958.3 5958.9 6024.0 6094.1 6145.7 6173.5 6294.0 6327.5 4906.6 4950.3 4961.5 4981.0 4996.2 4428.9 4432.1 4432.2 4447.6 4471.2 5680.9 5745.7 5762.3 5767.0 5775.2 6130.5 6217.8 6264.7 6299.1 6300.6 5754.7 5757.1 5760.4 5763.5 5767.7 5434.7 5437.9 5440.2 5442.1 5449.8 5195.5 6298.7 6316.8 6323.6 6336.3 6200.5 6204.2 6243.8 6314.7 6316.6 6342.4 6344.8 6353.5 6353.9 6355.8 6103.6 6136.1 6160.4 6182.0 6210.1 6210.4 6232.4 6233.8 6281.9 6308.8 6030.8 6168.4 6192.6 6198.2 6213.1 6061.2 6101.6 6115.5 6150.5 6169.8 4828.3 5903.6 5910.4 5951.2 6116.7 5255.5 5803.7 6181.8 6192.6 6194.3 5853.6 5922.3 5931.8 6013.2 6084.8 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:69990.07 Median:40840.99 stddev:154308.11 (377.8%) stddev-sans-first:154652.72 06:36:40 INFO - PROCESS | 1656 | Values: 474.5 493.7 534.2 538.7 547.7 28850.3 40554.0 41631.0 41792.0 44058.3 42722.8 43063.1 43176.4 43223.1 43252.8 9039.6 9831.2 9838.7 9840.4 9852.8 9673.7 9808.2 9809.8 9815.6 9829.7 9804.3 9814.4 9818.2 9828.0 9833.5 9753.9 9792.5 9794.3 9798.6 9842.5 9603.0 9680.0 9685.1 9687.9 9702.3 40847.4 41234.6 41281.6 41292.0 41410.6 2887.5 3012.6 3300.8 3381.4 3389.2 30861.8 31346.8 31766.0 31885.0 32571.3 3237.1 3247.3 3261.2 3280.4 3366.8 499101.0 500273.5 501475.0 502471.0 503157.0 60023.4 60195.4 60344.7 60356.0 60478.1 34736.1 35449.6 35506.8 36421.0 36444.8 16114.4 16317.7 16324.7 16331.5 16346.7 12415.0 12527.1 12532.4 12541.1 12554.5 18881.0 19061.7 19066.0 19135.3 19136.2 7915.4 7932.0 7958.2 8019.6 8043.4 890.5 964.0 972.9 973.3 1060.0 24525.6 29796.3 29944.9 29972.4 30280.1 974.4 983.0 1054.8 1088.2 1196.4 14569.9 15103.8 15736.9 15930.0 15943.7 665725.0 666716.0 669127.0 671990.0 675801.0 58820.7 59259.0 59272.7 59287.7 59341.1 1252.3 1294.5 1375.1 1406.5 1426.2 29286.0 31278.5 31639.0 31715.4 32054.4 49061.8 49577.5 49704.8 49714.0 49730.0 543923.0 546135.0 547357.3 547588.8 548632.0 23396.5 24028.9 26540.0 26907.0 27019.7 45015.0 47079.8 47914.8 49544.8 49886.3 39146.2 39184.2 39262.4 40089.4 40834.6 38681.8 38742.6 38810.4 39484.4 40201.8 38203.3 38614.0 38655.4 39347.8 39451.4 32471.0 35037.6 35048.0 35488.5 35577.6 23836.8 25017.0 25485.0 25550.5 26065.7 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33117.03 Median:387.96 stddev:96436.35 (24857.1%) stddev-sans-first:96675.49 06:36:40 INFO - PROCESS | 1656 | Values: 349.4 365.8 369.3 379.0 396.9 14907.6 15117.2 15877.3 20313.5 20741.3 19117.9 20051.9 20145.8 20315.9 20486.8 5665.5 5948.3 5959.3 5962.2 5965.0 4942.4 6664.5 6715.7 6741.9 6754.0 5638.7 5957.5 6675.1 6684.2 6686.2 5981.9 6017.7 6023.9 6035.0 6042.1 5038.3 5311.5 5368.5 5404.6 5798.0 12709.6 12751.2 12798.9 12882.0 12927.9 2092.5 2147.5 2271.1 2279.2 2402.1 9393.2 9574.9 9747.4 9977.9 10140.7 1747.8 1799.5 1845.2 1862.7 1871.8 107945.5 126207.0 126612.0 126665.0 127613.0 11069.4 11378.0 11393.5 11396.3 11631.7 14089.0 14099.2 14101.9 14127.0 14198.9 8201.5 8428.2 8477.2 8580.9 8582.7 8307.9 8311.2 8317.0 8474.0 8488.2 4289.4 4418.0 4424.3 4474.4 4490.9 1828.4 1897.1 1898.6 1901.0 1903.4 824.7 887.1 901.3 912.3 934.6 18417.9 20696.8 21598.1 21601.2 21656.6 748.3 796.6 912.5 923.8 968.8 9454.6 10234.2 10981.5 11083.6 11221.9 566359.0 568080.0 576550.4 587665.0 593282.0 82693.8 100473.0 112759.0 117677.4 123338.0 936.3 940.2 981.3 983.4 984.3 19007.4 19395.4 19611.0 19627.8 19643.4 26282.5 34606.5 47032.3 50741.0 51797.7 73145.6 75176.0 75908.6 76080.0 76641.6 7553.4 7762.9 7941.8 7997.2 8086.8 8928.9 11523.7 11827.9 12874.9 13003.3 5289.9 8828.5 10840.4 11150.8 11218.3 10758.2 10829.0 10903.7 11289.1 11323.2 10806.2 11150.3 11276.0 11343.3 11372.9 7720.4 7824.6 7850.3 7893.4 8022.4 8040.1 10524.4 10663.6 11039.1 11088.1 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5690.58 Median:4959.95 stddev:3204.99 (64.6%) stddev-sans-first:3198.32 06:36:40 INFO - PROCESS | 1656 | Values: 1475.5 1498.8 1710.8 1809.6 1829.7 7713.5 8353.7 8886.5 8893.6 8915.7 7862.7 8112.5 8485.0 8751.6 8771.0 2310.7 2344.2 2345.6 2347.8 2350.3 2319.6 2337.3 2343.5 2366.7 2367.4 2338.3 2350.6 2358.3 2368.6 2373.5 2329.5 2343.6 2348.4 2348.6 2355.5 2286.4 2328.8 2333.7 2339.0 2343.6 7695.5 8885.7 8950.0 8954.1 8991.3 5164.2 5314.7 5317.1 5320.2 5330.8 3467.2 3562.3 3709.5 3710.0 3723.8 1082.2 1083.1 1086.9 1106.5 1110.6 11616.5 11754.4 11764.9 11770.2 11794.7 4143.7 4485.0 4722.4 4730.5 4747.1 9861.7 9875.8 9877.1 9877.8 9892.4 5317.9 5320.9 5321.0 5323.7 5326.8 4188.7 4231.9 4394.2 4884.5 4889.5 3575.6 3660.0 3737.8 3768.9 3855.9 1257.1 1262.5 1269.9 1279.8 1280.2 799.2 861.6 890.2 929.5 937.4 5814.9 5943.5 5954.6 6113.6 6352.9 890.7 910.8 912.8 920.8 964.9 3685.7 3873.5 3908.0 3991.4 4031.8 10281.1 10556.7 10669.3 10724.2 10813.2 10770.4 10770.5 10770.8 10797.6 10802.7 7852.8 8258.9 8312.4 8334.2 8336.7 5713.3 5726.1 5733.9 5828.7 5858.6 9807.0 10138.9 10164.6 10205.0 10226.0 9813.0 10303.2 10314.7 10524.3 10534.3 6831.9 7080.8 7090.5 7117.6 7123.1 8234.7 8323.2 8337.0 8344.2 8370.0 7243.8 7314.7 7392.4 7558.0 7645.0 7278.3 7381.4 7406.0 7418.9 7521.7 6933.8 7102.6 7107.1 7107.9 7110.8 4887.2 4961.2 4966.3 5004.8 5101.4 4888.0 4958.7 5016.6 5068.4 5168.8 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4440.20 Median:4740.19 stddev:1380.84 (29.1%) stddev-sans-first:1348.68 06:36:40 INFO - PROCESS | 1656 | Values: 264.8 302.3 302.6 309.6 319.9 4713.7 5088.7 5182.6 5314.6 5331.6 4717.6 4812.2 5051.8 5144.3 5383.7 3283.0 4019.5 4146.2 4154.8 4155.5 3443.6 3938.9 4156.4 4169.7 4174.8 3437.0 4110.6 4118.3 4127.3 4159.3 4105.5 4105.6 4129.7 4130.9 4133.0 3590.0 3604.0 3614.6 3616.6 3617.4 4373.5 4990.6 5168.3 5238.8 5273.4 4703.3 4711.0 4712.7 4737.6 4742.8 5158.4 5227.2 5617.9 5630.7 5637.7 3689.9 3958.2 3967.3 3974.6 3977.7 5881.9 5926.8 5933.0 5936.3 5988.3 3752.1 4034.0 4036.6 4068.7 4075.0 3518.1 3771.4 4103.2 4112.8 4126.4 4480.8 4701.8 4707.8 4726.3 4743.5 5662.7 5669.7 5672.3 5696.5 5697.6 1215.1 1255.0 1306.6 1306.8 1376.8 787.3 820.5 820.5 839.9 872.5 4334.8 4381.3 4411.4 4426.9 4438.4 4164.4 4412.4 4773.9 4817.4 4820.5 4085.8 4203.7 4357.8 4371.5 4374.8 2232.0 2246.7 2305.6 2343.7 2344.6 4257.9 4450.0 4529.3 4576.4 4897.8 4238.6 4355.4 4707.4 4803.2 4874.8 4359.8 4841.0 5461.4 5928.3 5994.6 5060.2 5870.7 5883.5 5884.4 5884.9 5483.6 5817.7 5831.5 5884.4 5897.5 4988.5 5969.7 5990.9 6001.7 6025.5 3882.6 3932.8 3937.9 3953.4 3958.7 4929.5 5641.1 5955.6 5978.1 5998.5 5028.4 5080.1 5233.5 5460.5 5714.6 5231.4 5351.5 5709.5 5730.8 5796.3 5055.6 5299.6 5302.9 5421.5 5555.9 5238.3 5252.4 5694.9 5736.5 5737.3 5592.9 5614.9 5675.8 5677.0 5680.8 06:36:40 INFO - PROCESS | 1656 | 06:36:40 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24486.02 Median:3630.34 stddev:39455.89 (1086.8%) stddev-sans-first:39525.88 06:36:40 INFO - PROCESS | 1656 | Values: 618.5 746.2 761.5 785.1 826.6 15122.2 15162.5 15620.8 15928.5 16298.8 14222.8 14322.6 14323.6 14337.9 14343.2 856.0 883.8 887.2 887.2 891.8 701.2 724.5 857.7 879.1 890.3 861.3 909.0 932.4 933.3 935.5 925.2 926.7 927.6 929.6 933.7 535.5 556.2 558.0 558.3 559.2 12071.9 14878.6 14899.3 15186.2 15200.0 14289.4 14647.1 14728.3 14823.6 14945.7 10332.2 10537.5 11756.7 11813.7 12044.2 3578.4 3836.2 3849.7 3868.9 3997.7 84133.0 84854.9 102710.4 106142.5 110763.0 11179.2 11276.6 11334.8 11553.6 11703.1 23569.8 23571.8 23704.4 23763.4 23837.3 7752.2 7800.7 7857.8 7871.7 7966.8 4221.2 4246.9 4265.4 4309.0 4382.7 3515.7 3605.7 3619.3 3641.4 3645.0 648.8 651.8 654.4 657.7 666.8 602.0 652.9 665.6 675.9 683.8 8818.6 9187.0 9435.9 9465.0 9483.4 572.2 617.9 630.1 650.5 663.2 3864.2 4177.8 4487.0 4549.7 4650.3 96825.5 112329.0 145782.5 154784.0 161702.4 137176.0 138414.0 141087.6 143342.0 144160.3 76521.2 115963.8 118762.4 120713.0 121455.2 8825.9 8884.5 8894.6 8984.1 9083.2 64471.7 65918.0 66314.7 66968.4 68047.6 84285.0 95659.6 97930.5 99957.0 101779.5 11718.9 11956.7 12158.1 12220.9 12223.9 10780.9 12061.7 12846.7 12927.4 13355.5 12115.8 12122.6 12132.7 12139.8 12362.7 12165.5 12166.8 12216.3 12318.7 12329.5 10352.3 11829.7 12005.5 12100.5 12116.9 10149.4 10157.1 10184.4 10628.5 10707.9 10216.5 10454.3 10486.2 10494.2 10497.2 06:36:40 INFO - PROCESS | 1656 | -------- Summary: end -------- 06:36:40 INFO - PROCESS | 1656 | 06:36:41 INFO - TEST-INFO | 1656: exit 0 06:36:41 INFO - TEST-OK | dromaeo_css | took 1118790ms 06:36:41 INFO - TEST-START | kraken 06:36:41 INFO - operating with platform_type : mac_ 06:36:41 INFO - Initialising browser for kraken test... 06:36:41 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpu8Q8wk/profile http://localhost:49275/getInfo.html) 06:36:43 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:36:43 INFO - PROCESS | 1664 | colorDepth:24 06:36:43 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:36:43 INFO - PROCESS | 1664 | __metrics 06:36:43 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:36:43 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:36:44 INFO - TEST-INFO | 1664: exit 0 06:36:44 INFO - Browser initialized. 06:36:44 INFO - Running cycle 1/1 for kraken test... 06:36:44 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpu8Q8wk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:36:56 INFO - PROCESS | 1666 | RSS: Main: 235991040 06:36:56 INFO - PROCESS | 1666 | 06:36:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:36:59 INFO - PROCESS | 1666 | RSS: Main: 294522880 06:36:59 INFO - PROCESS | 1666 | 06:37:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:37:03 INFO - PROCESS | 1666 | RSS: Main: 295530496 06:37:03 INFO - PROCESS | 1666 | 06:37:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:37:07 INFO - PROCESS | 1666 | RSS: Main: 387022848 06:37:07 INFO - PROCESS | 1666 | 06:37:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:37:09 INFO - PROCESS | 1666 | RSS: Main: 288899072 06:37:09 INFO - PROCESS | 1666 | 06:37:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:37:12 INFO - PROCESS | 1666 | RSS: Main: 290430976 06:37:12 INFO - PROCESS | 1666 | 06:37:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:37:16 INFO - PROCESS | 1666 | RSS: Main: 326647808 06:37:16 INFO - PROCESS | 1666 | 06:37:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:37:19 INFO - PROCESS | 1666 | RSS: Main: 329707520 06:37:19 INFO - PROCESS | 1666 | 06:37:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:37:22 INFO - PROCESS | 1666 | RSS: Main: 325939200 06:37:22 INFO - PROCESS | 1666 | 06:37:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:37:25 INFO - PROCESS | 1666 | RSS: Main: 303702016 06:37:25 INFO - PROCESS | 1666 | 06:37:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:37:27 INFO - PROCESS | 1666 | RSS: Main: 305704960 06:37:27 INFO - PROCESS | 1666 | 06:37:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:37:30 INFO - PROCESS | 1666 | RSS: Main: 312979456 06:37:30 INFO - PROCESS | 1666 | 06:37:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:37:34 INFO - PROCESS | 1666 | RSS: Main: 324833280 06:37:34 INFO - PROCESS | 1666 | 06:37:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:37:37 INFO - PROCESS | 1666 | RSS: Main: 318889984 06:37:37 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:37:39 INFO - PROCESS | 1666 | RSS: Main: 363319296 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | __start_tp_report 06:37:39 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 06:37:39 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 06:37:39 INFO - PROCESS | 1666 | |i|pagename|runs| 06:37:39 INFO - PROCESS | 1666 | |0;ai-astar;113;115;115;114;114;114;113;114;114;114 06:37:39 INFO - PROCESS | 1666 | |1;audio-beat-detection;174;138;135;153;115;114;114;115;167;116 06:37:39 INFO - PROCESS | 1666 | |2;audio-dft;183;184;184;184;184;187;181;182;184;182 06:37:39 INFO - PROCESS | 1666 | |3;audio-fft;78;60;59;90;64;58;59;59;84;57 06:37:39 INFO - PROCESS | 1666 | |4;audio-oscillator;84;72;94;72;91;96;125;67;70;112 06:37:39 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;87;84;88;85;87;84;88;85;88;84 06:37:39 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;94;93;93;93;93;93;93;94;94 06:37:39 INFO - PROCESS | 1666 | |7;imaging-desaturate;87;84;84;86;84;84;87;83;86;86 06:37:39 INFO - PROCESS | 1666 | |8;json-parse-financial;56;55;54;52;54;55;54;54;55;55 06:37:39 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;70;67;64;75;71;67;68;70;69;70 06:37:39 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;88;85;87;87;83;86;88;94;91;88 06:37:39 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;147;145;148;144;144;146;143;157;186;146 06:37:39 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;174;167;172;170;172;163;180;171;170;172 06:37:39 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;70;70;68;68;69;72;69;61;70 06:37:39 INFO - PROCESS | 1666 | __end_tp_report 06:37:39 INFO - PROCESS | 1666 | __start_cc_report 06:37:39 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1977 06:37:39 INFO - PROCESS | 1666 | __end_cc_report 06:37:39 INFO - PROCESS | 1666 | __startTimestamp1475674659473__endTimestamp 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | ------- Summary: start ------- 06:37:39 INFO - PROCESS | 1666 | Number of tests: 14 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 06:37:39 INFO - PROCESS | 1666 | Values: 113.0 115.0 115.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:136.50 stddev:23.36 (17.1%) stddev-sans-first:19.82 06:37:39 INFO - PROCESS | 1666 | Values: 174.0 138.0 135.0 153.0 115.0 114.0 114.0 115.0 167.0 116.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.74 06:37:39 INFO - PROCESS | 1666 | Values: 183.0 184.0 184.0 184.0 184.0 187.0 181.0 182.0 184.0 182.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.00 stddev:12.34 (19.9%) stddev-sans-first:12.40 06:37:39 INFO - PROCESS | 1666 | Values: 78.0 60.0 59.0 90.0 64.0 58.0 59.0 59.0 84.0 57.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:78.00 stddev:19.28 (24.7%) stddev-sans-first:20.39 06:37:39 INFO - PROCESS | 1666 | Values: 84.0 72.0 94.0 72.0 91.0 96.0 125.0 67.0 70.0 112.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 06:37:39 INFO - PROCESS | 1666 | Values: 87.0 84.0 88.0 85.0 87.0 84.0 88.0 85.0 88.0 84.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.50 06:37:39 INFO - PROCESS | 1666 | Values: 94.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 94.0 94.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.36 06:37:39 INFO - PROCESS | 1666 | Values: 87.0 84.0 84.0 86.0 84.0 84.0 87.0 83.0 86.0 86.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.97 06:37:39 INFO - PROCESS | 1666 | Values: 56.0 55.0 54.0 52.0 54.0 55.0 54.0 54.0 55.0 55.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:70.00 stddev:2.92 (4.2%) stddev-sans-first:3.08 06:37:39 INFO - PROCESS | 1666 | Values: 70.0 67.0 64.0 75.0 71.0 67.0 68.0 70.0 69.0 70.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:3.06 (3.5%) stddev-sans-first:3.24 06:37:39 INFO - PROCESS | 1666 | Values: 88.0 85.0 87.0 87.0 83.0 86.0 88.0 94.0 91.0 88.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:146.50 stddev:13.05 (8.9%) stddev-sans-first:13.77 06:37:39 INFO - PROCESS | 1666 | Values: 147.0 145.0 148.0 144.0 144.0 146.0 143.0 157.0 186.0 146.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:172.00 stddev:4.41 (2.6%) stddev-sans-first:4.55 06:37:39 INFO - PROCESS | 1666 | Values: 174.0 167.0 172.0 170.0 172.0 163.0 180.0 171.0 170.0 172.0 06:37:39 INFO - PROCESS | 1666 | 06:37:39 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.50 stddev:2.91 (4.2%) stddev-sans-first:3.09 06:37:39 INFO - PROCESS | 1666 | Values: 69.0 70.0 70.0 68.0 68.0 69.0 72.0 69.0 61.0 70.0 06:37:39 INFO - PROCESS | 1666 | -------- Summary: end -------- 06:37:39 INFO - PROCESS | 1666 | 06:37:40 INFO - TEST-INFO | 1666: exit 0 06:37:40 INFO - TEST-OK | kraken | took 59258ms 06:37:40 INFO - SUITE-END | took 1178s 06:37:40 INFO - Completed test suite (00:19:38) 06:37:40 INFO - javascript benchmark 06:37:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3316.602007759069, 3330.5670262865833, 3335.132865454981, 3344.4983252512134, 3391.6608339166073, 5643.746031428585, 5681.23303354047, 5738.3974682658345, 5743.282089738783, 5807.767689292434, 5707.5148612368, 5709.086546152616, 5789.4500022497905, 5832.62087964282, 5885.735141918613, 5148.922766158511, 5190.714510701929, 5238.842834714965, 5250.684958902459, 5266.868328291805, 5346.946530534684, 5362.0, 5364.168553874136, 5377.462253774611, 5380.784768609251, 5322.520973112439, 5340.973295133538, 5353.678908918627, 5361.839144825642, 5418.593605479585, 5150.227465880111, 5194.610404219661, 5227.47725227478, 5234.084035293847, 5246.816361427332, 5113.53978141969, 5285.048691235576, 5289.735513224324, 5295.285136506599, 5297.8145764898045, 5526.4197259288, 5616.494515493625, 5618.7190640468225, 5636.210930469738, 5645.32256129263, 6098.298695650013, 6108.572399932006, 6121.051237058263, 6123.234595675541, 6124.142620033199, 4923.48303428142, 6141.877162456771, 6151.138840562325, 6164.815055548341, 6231.501479881644, 5784.88430231393, 5790.10253410722, 5791.681457519792, 5794.101914203306, 5800.912986305167, 6334.809955701337, 6337.9683101585115, 6342.778002769881, 6358.364163583605, 6360.554761166674, 5854.882902341929, 5895.02732049207, 6006.699665016732, 6082.9695851520455, 6097.268327800603, 6058.030715085653, 6060.33336333003, 6063.060225665029, 6116.235470566179, 6136.6011209271255, 5862.853428664317, 5948.613340132878, 5958.344582095966, 5958.940410595926, 6023.969880150571, 6094.094888519665, 6145.678679084019, 6173.536984726164, 6294.024426213944, 6327.525435592349, 4906.55840974314, 4950.257461380822, 4961.479044700254, 4980.950190498121, 4996.225585034325, 4428.933565996513, 4432.113577284556, 4432.224360736858, 4447.62195213404, 4471.217536931024, 5680.914786278209, 5745.6552606843725, 5762.337331206881, 5766.961946849608, 5775.220345253337, 6130.52043589939, 6217.813465596039, 6264.655443950626, 6299.118123462719, 6300.621962682254, 5754.654720716687, 5757.0788673811985, 5760.3951585083805, 5763.538916886639, 5767.711614419262, 5434.728263586884, 5437.864053398696, 5440.238366628675, 5442.12925931849, 5449.782008719686, 5195.506426889439, 6298.716557755004, 6316.778912738032, 6323.620582765049, 6336.303006669171, 6200.534959877937, 6204.162438070803, 6243.843903902439, 6314.715837787402, 6316.62100273985, 6342.365763423621, 6344.809655710336, 6353.4599559038, 6353.9046914295395, 6355.7775477859905, 6103.633781973096, 6136.140940268277, 6160.353162917829, 6182.0, 6210.099535567408, 6210.441060304594, 6232.407921247474, 6233.812985610439, 6281.874362512633, 6308.779192728232, 6030.819075427744, 6168.35232300602, 6192.59748116371, 6198.2252218472695, 6213.068039794067, 6061.1514387984835, 6101.572889897842, 6115.53329557081, 6150.531413494974, 6169.81490555284, 4828.275758636232, 5903.645781252967, 5910.408959104056, 5951.226340575698, 6116.663583502772, 5255.474452554714, 5803.738831752666, 6181.752729890754, 6192.59748116371, 6194.256689197326, 5853.590248682722, 5922.2597175353085, 5931.762729490944, 6013.158157857991, 6084.7566097357385], "value": 5685.494232909093, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.4994031296981, 493.664308270376, 534.1747000883637, 538.7333270031334, 547.6577139287945, 28850.278743031435, 40554.0, 41631.0, 41792.0, 44058.33912491315, 42722.78638606802, 43063.138737225236, 43176.352354714705, 43223.135537289316, 43252.78373608136, 9039.63841446343, 9831.213502919785, 9838.655647052354, 9840.409575425461, 9852.753681157992, 9673.661421850236, 9808.16630586401, 9809.754756131118, 9815.558299876488, 9829.65596204133, 9804.31369804114, 9814.362066465657, 9818.165455936254, 9828.017198280151, 9833.459159746211, 9753.902460975405, 9792.510374481248, 9794.314397992144, 9798.6080556778, 9842.458664773434, 9603.039696030412, 9680.031996800333, 9685.079917407835, 9687.903120968771, 9702.27232957524, 40847.38728919069, 41234.587654123374, 41281.587184128075, 41292.0, 41410.585894140975, 2887.4946884295373, 3012.638483382008, 3300.8019518828705, 3381.4420620597816, 3389.2204792897774, 30861.845690771628, 31346.843265783755, 31766.0, 31885.0, 32571.34857302841, 3237.1097948064235, 3247.318063206729, 3261.1521004538754, 3280.3931272714626, 3366.831658417094, 499101.0, 500273.49863250816, 501475.0, 502471.0, 503157.0, 60023.39976600266, 60195.39804601986, 60344.69827650834, 60356.0, 60478.09282860761, 34736.131596710286, 35449.64550354515, 35506.75726349565, 36421.0, 36444.817775911484, 16114.435994740363, 16317.67364652724, 16324.673506529803, 16331.510054698378, 16346.673066538602, 12415.0, 12527.060470464752, 12532.373381330897, 12541.059420543495, 12554.497820087277, 18881.0, 19061.714074288895, 19065.951372674637, 19135.330263440992, 19136.234550618097, 7915.366770658334, 7932.000079439178, 7958.164392738795, 8019.639116239783, 8043.39674524413, 890.5057692980329, 963.9662611808553, 972.9221662267001, 973.2603221551672, 1060.0353678152844, 24525.63211551828, 29796.33441928317, 29944.850275748482, 29972.400551988838, 30280.091597252118, 974.4202199691173, 983.0, 1054.8048611006989, 1088.2382332367374, 1196.407778149804, 14569.854301457062, 15103.848961510465, 15736.921315393578, 15929.964552304064, 15943.68112637764, 665725.0, 666716.0, 669127.0, 671990.0, 675801.0, 58820.7058964711, 59259.0, 59272.70363648154, 59287.70356148278, 59341.10988335178, 1252.2611659121162, 1294.501616877509, 1375.0580852116332, 1406.5499040307209, 1426.2227086238024, 29285.974990875216, 31278.53082203814, 31638.957705280234, 31715.365692685555, 32054.35891282208, 49061.75469122775, 49577.504224958735, 49704.75738106576, 49714.0, 49730.0, 543923.0, 546135.0, 547357.2632136814, 547588.7861682158, 548632.0, 23396.532069358178, 24028.87985560131, 26540.0, 26907.0, 27019.72980270172, 45015.0, 47079.76460117677, 47914.80212994703, 49544.75227623839, 49886.251706225165, 39146.23841927177, 39184.21631567296, 39262.41106383464, 40089.39865902072, 40834.59165408308, 38681.806590966866, 38742.6125738739, 38810.417843731804, 39484.40773388459, 40201.798991004856, 38203.33180741649, 38614.034649133995, 38655.42016869805, 39347.80326098351, 39451.40822887716, 32471.0, 35037.64962350446, 35048.0, 35488.46767298544, 35577.64422355847, 23836.761632384147, 25016.999320026996, 25485.0, 25550.488990220463, 26065.73934260633], "value": 19801.44897982936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.4217070747913, 365.77139287945033, 369.33150996695963, 378.98673546425874, 396.9384745364468, 14907.5527734168, 15117.168555729444, 15877.285522151502, 20313.49216269594, 20741.274055408063, 19117.904410477928, 20051.899740501278, 20145.79125252482, 20315.898420507878, 20486.795132048675, 5665.5467562594895, 5948.286205655306, 5959.255093113361, 5962.224910761616, 5964.970175149118, 4942.382202224722, 6664.533482656216, 6715.664216789165, 6741.865162696743, 6754.020667003281, 5638.661680299175, 5957.463828255456, 6675.132232809752, 6684.231313398973, 6686.2310834254195, 5981.910271345933, 6017.729202185912, 6023.909641355362, 6034.969825150846, 6042.06348016056, 5038.319826823368, 5311.521963023347, 5368.463153684639, 5404.648697834628, 5798.0, 12709.555165569162, 12751.171173873761, 12798.872011279862, 12882.033847461478, 12927.935360323232, 2092.5396412789164, 2147.5060736030646, 2271.136967952184, 2279.1681036421587, 2402.1112188490197, 9393.154616084585, 9574.904250957541, 9747.409487984149, 9977.90022099777, 10140.695779126565, 1747.7815273090864, 1799.5321216483808, 1845.2250054976942, 1862.6554087493857, 1871.7941026487076, 107945.46027269893, 126207.0, 126612.0, 126665.0, 127613.0, 11069.446527673585, 11378.0, 11393.487293071832, 11396.259243149168, 11631.6510504685, 14089.0, 14099.224542650047, 14101.884717292489, 14127.0, 14198.929005355114, 8201.466904651179, 8428.157184281523, 8477.194666506672, 8580.871286930702, 8582.742517724479, 8307.875381869277, 8311.210435008665, 8317.043539992857, 8473.957630211933, 8488.193621605938, 4289.420928174719, 4418.02609713167, 4424.336349547593, 4474.37358769766, 4490.932636010462, 1828.4057681253646, 1897.1178402043117, 1898.6487499812304, 1901.0, 1903.4099429177, 824.744329257932, 887.1173182683272, 901.3420203251663, 912.3385545479474, 934.6121359635811, 18417.907910460362, 20696.79303206979, 21598.13607455684, 21601.243956461763, 21656.56686866254, 748.3078152708831, 796.6096612659755, 912.5254867469012, 923.8106188231396, 968.8159249742527, 9454.621815127455, 10234.181265498743, 10981.505832237568, 11083.612073577386, 11221.887781122085, 566359.0, 568080.0, 576550.3517447242, 587665.0, 593282.0, 82693.75959360614, 100472.99527004783, 112759.0, 117677.41161294139, 123338.0, 936.2977766674994, 940.1867384712311, 981.2787601113318, 983.4148681534475, 984.3306551544875, 19007.42977710671, 19395.418137455334, 19611.0, 19627.8037219626, 19643.410697679094, 26282.474350513265, 34606.480902786985, 47032.29451558161, 50741.0, 51797.741011294704, 73145.63427183042, 75176.0, 75908.62045689957, 76080.0, 76641.61679191569, 7553.357964573072, 7762.910100109075, 7941.801454963672, 7997.200279971957, 8086.838263234585, 8928.910710892807, 11523.654290371302, 11827.940860295643, 12874.935625321814, 13003.284819335027, 5289.947100528945, 8828.470291782514, 10840.403777792297, 11150.776984460103, 11218.326900386002, 10758.193135515025, 10829.0, 10903.672889813319, 11289.096872250037, 11323.207375483636, 10806.18953578469, 11150.330980141218, 11276.041536469493, 11343.277613372897, 11372.886271137513, 7720.382369410546, 7824.608769561613, 7850.293473587404, 7893.36853051766, 8022.438429309892, 8040.075391330072, 10524.421156836448, 10663.57345706194, 11039.061679757311, 11088.057515111306], "value": 9093.690676078133, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.52783109405, 1498.8426215247398, 1710.8374704403077, 1809.6290260496598, 1829.7283388045314, 7713.4986225895345, 8353.749387518368, 8886.511241881693, 8893.644254229823, 8915.732528024162, 7862.6854925803045, 8112.513249205037, 8484.95757521213, 8751.606177722002, 8771.0, 2310.734265559457, 2344.2146880794953, 2345.6012477878717, 2347.816530626517, 2350.2949115265437, 2319.5824751544715, 2337.2754446121758, 2343.472718638301, 2366.692329997098, 2367.4199821043867, 2338.286822519131, 2350.6356514740155, 2358.3278765551822, 2368.5854975379325, 2373.5134297469062, 2329.464223228667, 2343.6015877300815, 2348.377679914824, 2348.5889969255313, 2355.5406695694346, 2286.4398222435443, 2328.80205182558, 2333.708286464192, 2338.988305058481, 2343.61330380487, 7695.461317707763, 8885.733427997171, 8949.955250223773, 8954.149355811187, 8991.280697544245, 5164.173732202867, 5314.73426328682, 5317.069512835238, 5320.228566857822, 5330.813421530266, 3467.167879708877, 3562.2697347043795, 3709.4992176056157, 3710.0, 3723.7579557328686, 1082.182951871332, 1083.06315037492, 1086.9184811139196, 1106.4965440724532, 1110.572429614596, 11616.477258523386, 11754.353510557, 11764.941175294154, 11770.2349347293, 11794.705132371759, 4143.6685065194715, 4484.977575112103, 4722.409698787652, 4730.5032971538185, 4747.074320507468, 9861.654842080636, 9875.802483950281, 9877.11106000449, 9877.753056173653, 9892.406455612543, 5317.8936421271355, 5320.893582128336, 5321.042212401728, 5323.653962492425, 5326.760295786621, 4188.6649068074485, 4231.87304380869, 4394.165108629414, 4884.511548845087, 4889.462159162489, 3575.588807287143, 3659.9935017869766, 3737.831797569116, 3768.8680896168503, 3855.9036024099623, 1257.076049103908, 1262.50762202741, 1269.930153841529, 1279.8400199975003, 1280.238258236348, 799.2327365728966, 861.5950503263456, 890.1632465482426, 929.4609126706493, 937.4281688170211, 5814.912776308359, 5943.524518038546, 5954.612950158225, 6113.602615829956, 6352.872942541123, 890.6570970176529, 910.7632015675972, 912.8311262416474, 920.8112336971038, 964.9179819715403, 3685.6867166291167, 3873.4964454620717, 3907.9804600976813, 3991.3613821788954, 4031.7580945143386, 10281.074703276592, 10556.736081598021, 10669.253152279265, 10724.215280091932, 10813.243072984815, 10770.407627580558, 10770.515326810313, 10770.838437423445, 10797.568097276178, 10802.67591972242, 7852.764417067498, 8258.876116858251, 8312.41813073079, 8334.249917507454, 8336.708215212439, 5713.285839270091, 5726.141078838207, 5733.913991290217, 5828.679422631796, 5858.560607954506, 9807.019298070136, 10138.949305253427, 10164.593416263267, 10205.0, 10226.0, 9813.01869813013, 10303.227257955834, 10314.690559283234, 10524.263301568817, 10534.315269507457, 6831.897521537082, 7080.787576372717, 7090.539114957512, 7117.572945623279, 7123.109611298588, 8234.711785087493, 8323.16768323187, 8336.958315208385, 8344.249017588447, 8369.95815020921, 7243.818904527429, 7314.6708398121145, 7392.37164840973, 7557.962210188914, 7645.044369453818, 7278.272172782679, 7381.372583330477, 7406.037215161994, 7418.888716669362, 7521.661525231487, 6933.791986240421, 7102.609356485443, 7107.147142342952, 7107.928920710933, 7110.786676399716, 4887.218045112907, 4961.206207006861, 4966.329545511454, 5004.77478513475, 5101.362329708786, 4888.0, 4958.7024778513405, 5016.648834581544, 5068.417132029864, 5168.8449346519665], "value": 4570.635150259539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.8109986162132, 302.2700179067552, 302.5824362379914, 309.61762223653784, 319.9072269041978, 4713.693609915357, 5088.694678319294, 5182.585393168547, 5314.5748340132795, 5331.626786124973, 4717.5518325759085, 4812.23004319309, 5051.751970373185, 5144.25408315794, 5383.67697938124, 3282.96717032829, 4019.477467929165, 4146.170765846828, 4154.833806647731, 4155.4832486142495, 3443.6397622938925, 3938.8621398251066, 4156.418101465797, 4169.687273454488, 4174.77038762868, 3436.9656303436896, 4110.568390319018, 4118.299889018859, 4127.277726397883, 4159.2721273777115, 4105.507339119295, 4105.5894410559, 4129.690273229489, 4130.855420060314, 4132.979335103335, 3589.982050089759, 3604.0, 3614.638536146391, 3616.566012078541, 3617.366960781853, 4373.497047839507, 4990.600751939836, 5168.250603662448, 5238.816641417532, 5273.393559740641, 4703.318018887277, 4710.952890471086, 4712.69367491112, 4737.59730422911, 4742.786574604151, 5158.38099428071, 5227.222744266929, 5617.9438205618235, 5630.662160270397, 5637.746301416446, 3689.8524059037604, 3958.2083583283456, 3967.266055779661, 3974.5826688197903, 3977.707245145339, 5881.911771323434, 5926.762929482816, 5933.0, 5936.347001829795, 5988.251468566429, 3752.080740218609, 4033.9596604034173, 4036.555978842325, 4068.6745060395097, 4074.9796251018556, 3518.0677120563073, 3771.358868992278, 4103.158852435244, 4112.814923328458, 4126.442930204384, 4480.843170489017, 4701.764911754467, 4707.835225767081, 4726.338312636233, 4743.478217396084, 5662.696350773442, 5669.688167150764, 5672.319321681342, 5696.458836410535, 5697.57268204878, 1215.0826126274605, 1254.993725031369, 1306.6406738147086, 1306.843178818529, 1376.8347798264272, 787.322902304018, 820.4666966471841, 820.5363969357294, 839.8530257204965, 872.4634349874781, 4334.826606935687, 4381.342798580238, 4411.448568928884, 4426.911461770746, 4438.423005009393, 4164.3961625564125, 4412.426384569985, 4773.880652983703, 4817.373741413594, 4820.457453614809, 4085.816138273785, 4203.70574059819, 4357.760323182255, 4371.513685346934, 4374.753195339313, 2231.977680223177, 2246.7081428178863, 2305.6281512500036, 2343.695319608474, 2344.6401087369286, 4257.891135054687, 4449.974017668017, 4529.343245229489, 4576.359309696645, 4897.755112244445, 4238.642036944583, 4355.390245365651, 4707.388039554836, 4803.231482962779, 4874.805007799649, 4359.760213188241, 4840.951590484121, 5461.42655021225, 5928.288605367384, 5994.610350327128, 5060.190369541003, 5870.735816888336, 5883.499902508334, 5884.411558844081, 5884.852878678067, 5483.616146869681, 5817.738201780845, 5831.533477321889, 5884.352721200579, 5897.469227769521, 4988.476209997898, 5969.7313620888035, 5990.891685038107, 6001.669908155093, 6025.548083893638, 3882.611738826095, 3932.783696896698, 3937.9409308859817, 3953.4465174874967, 3958.6833053355085, 4929.482404347492, 5641.082032109102, 5955.553333499918, 5978.103284507359, 5998.520118390606, 5028.446870844277, 5080.060188865072, 5233.476652334736, 5460.453954604508, 5714.571407144397, 5231.372235331784, 5351.491608297206, 5709.457601527848, 5730.770769169187, 5796.304443466811, 5055.595552355876, 5299.576033917355, 5302.859885124717, 5421.473069986448, 5555.916661250004, 5238.345206849144, 5252.448492908343, 5694.943050569441, 5736.5410767139365, 5737.307494289278, 5592.944070559242, 5614.85962850932, 5675.801346952837, 5677.0, 5680.801171958961], "value": 3994.104954684119, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.5175563060812, 746.1754760989108, 761.5088268067096, 785.1285073568338, 826.6280173921737, 15122.243887805607, 15162.469313574027, 15620.843791562082, 15928.522144335673, 16298.755518667229, 14222.786658200135, 14322.641933951656, 14323.64190895228, 14337.856621433808, 14343.211123388235, 855.9786005349854, 883.7525492862009, 887.1926546842375, 887.2103827593447, 891.7681402835244, 701.1516065560678, 724.5145752345915, 857.6998050682242, 879.1164879296298, 890.2610833008588, 861.3496809908511, 908.9772755681128, 932.3753085432743, 933.2906990687093, 935.4527601353217, 925.2181906289175, 926.6941909169958, 927.5825878354734, 929.5631053404888, 933.6872147830485, 535.4725595288637, 556.210181542211, 558.045741781554, 558.3355806590185, 559.1863838115532, 12071.93964030183, 14878.628034299121, 14899.255037248202, 15186.164760938143, 15200.0, 14289.428422863073, 14647.121172729563, 14728.263586820616, 14823.555293341216, 14945.701085978328, 10332.224263793032, 10537.473126343652, 11756.706082347924, 11813.704657383634, 12044.156909016372, 3578.4453409721536, 3836.156045669948, 3849.6727778138893, 3868.883933482, 3997.6601988830985, 84133.0, 84854.87862803417, 102710.3780329887, 106142.46928765385, 110763.0, 11179.21745477825, 11276.605318813803, 11334.773304533863, 11553.595624153115, 11703.122265830098, 23569.764302356758, 23571.7642823573, 23704.40738981505, 23763.405914851923, 23837.284881453583, 7752.224777522316, 7800.726974555863, 7857.764267071997, 7871.724489642835, 7966.800829979181, 4221.155768846182, 4246.8725938221905, 4265.44294410133, 4308.978455107705, 4382.693211475166, 3515.7187425005936, 3605.7475976681903, 3619.3123306571665, 3641.362761516724, 3644.9817750911075, 648.7988723495731, 651.7621068310033, 654.4012228810681, 657.7369052378991, 666.823291827666, 601.9548533859978, 652.9379708927644, 665.6139439125295, 675.8817206988757, 683.8085336105898, 8818.647254109892, 9187.0, 9435.85545199958, 9465.0, 9483.430994140374, 572.1960645293369, 617.8980468222783, 630.0706457974543, 650.5381179362705, 663.184283331505, 3864.169203621179, 4177.770222637784, 4487.012857171417, 4549.727016379028, 4650.348951146842, 96825.5158724216, 112329.0, 145782.54217457902, 154784.0, 161702.38297616874, 137176.0, 138414.0, 141087.57252732417, 143342.0, 144160.27919860333, 76521.23478765253, 115963.84036159699, 118762.4061879685, 120713.0, 121455.17817232749, 8825.852639156868, 8884.466931984101, 8894.644214231359, 8984.146506081912, 9083.227925626396, 64471.677641611495, 65918.01122983255, 66314.67370652656, 66968.39764967514, 68047.63904721977, 84285.0, 95659.56510652485, 97930.51034744781, 99957.0, 101779.49110254401, 11718.88281117212, 11956.701082473008, 12158.148929574845, 12220.877791221974, 12223.93888030554, 10780.892191078303, 12061.698457538632, 12846.67883302925, 12927.353632318534, 13355.53255636048, 12115.818262726243, 12122.636320910387, 12132.696682583006, 12139.75720485603, 12362.690932726753, 12165.462198221261, 12166.75666486683, 12216.328101954477, 12318.692032699255, 12329.506819727469, 10352.327098738559, 11829.704257393634, 12005.459754310903, 12100.515979360727, 12116.878831211927, 10149.39103653783, 10157.085862272135, 10184.388936663823, 10628.52171652262, 10707.89292107069, 10216.489175541343, 10454.320469169785, 10486.16110711126, 10494.160467162455, 10497.2127090467], "value": 7635.608949550999, "unit": "score"}], "name": "dromaeo_css", "value": 7229.060940135754, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 138.0, 135.0, 153.0, 115.0, 114.0, 114.0, 115.0, 167.0, 116.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 184.0, 184.0, 187.0, 181.0, 182.0, 184.0, 182.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 59.0, 90.0, 64.0, 58.0, 59.0, 59.0, 84.0, 57.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 94.0, 72.0, 91.0, 96.0, 125.0, 67.0, 70.0, 112.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 86.0, 84.0, 84.0, 87.0, 83.0, 86.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 52.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 64.0, 75.0, 71.0, 67.0, 68.0, 70.0, 69.0, 70.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 87.0, 83.0, 86.0, 88.0, 94.0, 91.0, 88.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 148.0, 144.0, 144.0, 146.0, 143.0, 157.0, 186.0, 146.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 167.0, 172.0, 170.0, 172.0, 163.0, 180.0, 171.0, 170.0, 172.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 68.0, 68.0, 69.0, 72.0, 69.0, 61.0, 70.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1452.6999999999998, "alertThreshold": 2.0}]} 06:37:40 INFO - Return code: 0 06:37:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:37:40 INFO - # TBPL SUCCESS # 06:37:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:37:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:37:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:37:40 INFO - 2016-10-05 06:37:40.782 screenresolution[1670:9824] starting screenresolution argv=screenresolution get 06:37:40 INFO - 2016-10-05 06:37:40.785 screenresolution[1670:9824] Display 0: 1600x1200x32@60 06:37:40 INFO - 2016-10-05 06:37:40.791 screenresolution[1671:9827] starting screenresolution argv=screenresolution list 06:37:40 INFO - Available Modes on Display 0 06:37:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:37:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:37:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:37:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:37:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:37:41 INFO - Intel Iris: 06:37:41 INFO - Chipset Model: Intel Iris 06:37:41 INFO - Type: GPU 06:37:41 INFO - Bus: Built-In 06:37:41 INFO - VRAM (Dynamic, Max): 1536 MB 06:37:41 INFO - Vendor: Intel (0x8086) 06:37:41 INFO - Device ID: 0x0a2e 06:37:41 INFO - Revision ID: 0x0009 06:37:41 INFO - Displays: 06:37:41 INFO - Display: 06:37:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:37:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:37:41 INFO - Display Serial Number: 1600x1200 60 06:37:41 INFO - Main Display: Yes 06:37:41 INFO - Mirror: Off 06:37:41 INFO - Online: Yes 06:37:41 INFO - Rotation: Supported 06:37:41 INFO - Return code: 0 06:37:41 INFO - Running post-action listener: _resource_record_post_action 06:37:41 INFO - [mozharness: 2016-10-05 13:37:41.041291Z] Finished run-tests step (success) 06:37:41 INFO - Running post-run listener: _resource_record_post_run 06:37:41 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37438464; Write bytes: 712061440; Read time: 249; Write time: 2013 06:37:41 INFO - TinderboxPrint: CPU usage
53.9% 06:37:41 INFO - TinderboxPrint: I/O read bytes / time
37,438,464 / 249 06:37:41 INFO - TinderboxPrint: I/O write bytes / time
712,061,440 / 2,013 06:37:41 INFO - TinderboxPrint: CPU idle
2,236.5 (46.1%) 06:37:41 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 06:37:41 INFO - TinderboxPrint: CPU user
1,643.9 (33.9%) 06:37:41 INFO - TinderboxPrint: Swap in / out
445,120,512 / 0 06:37:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404455936; Write bytes: 399201792; Read time: 29759; Write time: 821 06:37:41 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35308544; Write bytes: 299953152; Read time: 226; Write time: 1153 06:37:41 INFO - Running post-run listener: _upload_blobber_files 06:37:41 INFO - Blob upload gear active. 06:37:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:37:41 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:37:41 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:37:41 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:37:41 INFO - (blobuploader) - INFO - Open directory for files ... 06:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:43 INFO - (blobuploader) - INFO - Done attempting. 06:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:43 INFO - (blobuploader) - INFO - Done attempting. 06:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:37:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:47 INFO - (blobuploader) - INFO - Done attempting. 06:37:47 INFO - (blobuploader) - INFO - Iteration through files over. 06:37:47 INFO - Return code: 0 06:37:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:37:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:37:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4987ccf2cf5fccd8616c7c78afc2fb9ae4142da57576d3a0724c1fcb39a6dadfa64f483e292e1fbc8ee5a60b5ac4742c7c7b86ddddc6194d82501a8369802b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ded1d3fd0cd1386802284c0a514f48b954b7a685c2c95f014d92c83e2982b67a74818bedee8029c245f5c955ced8974a8eced34724e75f10319bbc7784f9ea96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96df61cabf4c0e1a780e6ca588cd1b1121be18fa28e2663b54427e253b07669e9c80e23921593038ae7fe686fd3b4880884bdf2b0fe75229482a40b478ffa70c"} 06:37:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:37:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:37:47 INFO - Contents: 06:37:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4987ccf2cf5fccd8616c7c78afc2fb9ae4142da57576d3a0724c1fcb39a6dadfa64f483e292e1fbc8ee5a60b5ac4742c7c7b86ddddc6194d82501a8369802b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ded1d3fd0cd1386802284c0a514f48b954b7a685c2c95f014d92c83e2982b67a74818bedee8029c245f5c955ced8974a8eced34724e75f10319bbc7784f9ea96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96df61cabf4c0e1a780e6ca588cd1b1121be18fa28e2663b54427e253b07669e9c80e23921593038ae7fe686fd3b4880884bdf2b0fe75229482a40b478ffa70c"} 06:37:47 INFO - Running post-run listener: copy_logs_to_upload_dir 06:37:47 INFO - Copying logs to upload dir... 06:37:47 INFO - mkdir: /builds/slave/test/build/upload/logs 06:37:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.927447 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-05 06:37:47.196372) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 06:37:47.199694) ========= 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.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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/fc4987ccf2cf5fccd8616c7c78afc2fb9ae4142da57576d3a0724c1fcb39a6dadfa64f483e292e1fbc8ee5a60b5ac4742c7c7b86ddddc6194d82501a8369802b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ded1d3fd0cd1386802284c0a514f48b954b7a685c2c95f014d92c83e2982b67a74818bedee8029c245f5c955ced8974a8eced34724e75f10319bbc7784f9ea96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96df61cabf4c0e1a780e6ca588cd1b1121be18fa28e2663b54427e253b07669e9c80e23921593038ae7fe686fd3b4880884bdf2b0fe75229482a40b478ffa70c"} build_url:https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4987ccf2cf5fccd8616c7c78afc2fb9ae4142da57576d3a0724c1fcb39a6dadfa64f483e292e1fbc8ee5a60b5ac4742c7c7b86ddddc6194d82501a8369802b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ded1d3fd0cd1386802284c0a514f48b954b7a685c2c95f014d92c83e2982b67a74818bedee8029c245f5c955ced8974a8eced34724e75f10319bbc7784f9ea96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96df61cabf4c0e1a780e6ca588cd1b1121be18fa28e2663b54427e253b07669e9c80e23921593038ae7fe686fd3b4880884bdf2b0fe75229482a40b478ffa70c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 06:37:47.232386) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:37:47.232804) ========= 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.LiXKLQYzmg/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.risXsnL6t1/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.005183 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:37:47.281304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 06:37:47.281680) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 06:37:47.282025) ========= ========= Total master_lag: 0.18 =========