builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0321 starttime: 1479473189.18 results: success (0) buildid: 20161118021428 builduid: db273267aa5c47ee9bbf26bf7c372bc2 revision: 594e6d7a00aff726c2284e8a40bd0b03d63c7187 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.182668) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.183038) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.183303) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.207420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.207708) ========= 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.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 04:46:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2016-11-18 04:46:29 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123864 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.353431) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.353738) ========= 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.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.025249 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:29.401318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 04:46:29.401638) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 594e6d7a00aff726c2284e8a40bd0b03d63c7187 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 594e6d7a00aff726c2284e8a40bd0b03d63c7187 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 04:46:29,500 truncating revision to first 12 chars 2016-11-18 04:46:29,500 Setting DEBUG logging. 2016-11-18 04:46:29,500 attempt 1/10 2016-11-18 04:46:29,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/594e6d7a00af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 04:46:30,597 unpacking tar archive at: mozilla-aurora-594e6d7a00af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 04:46:30.776565) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:30.776915) ========= script_repo_revision: 594e6d7a00aff726c2284e8a40bd0b03d63c7187 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:30.777296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:30.777578) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 04:46:30.795625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-18 04:46:30.795932) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:46:30 INFO - MultiFileLogger online at 20161118 04:46:30 in /builds/slave/test 04:46:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:46:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:30 INFO - {'append_to_log': False, 04:46:30 INFO - 'base_work_dir': '/builds/slave/test', 04:46:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:46:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:30 INFO - 'branch': 'Mozilla-Aurora', 04:46:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:30 INFO - 'config_files': ('talos/mac_config.py',), 04:46:30 INFO - 'default_actions': ('clobber', 04:46:30 INFO - 'read-buildbot-config', 04:46:30 INFO - 'download-and-extract', 04:46:30 INFO - 'populate-webroot', 04:46:30 INFO - 'create-virtualenv', 04:46:30 INFO - 'install', 04:46:30 INFO - 'run-tests'), 04:46:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:30 INFO - 'download_minidump_stackwalk': True, 04:46:30 INFO - 'download_symbols': 'ondemand', 04:46:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:30 INFO - 'installer_path': 'installer.exe', 04:46:30 INFO - 'log_level': 'info', 04:46:30 INFO - 'log_name': 'talos', 04:46:30 INFO - 'log_to_console': True, 04:46:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:30 INFO - 'opt_config_files': (), 04:46:30 INFO - 'pip_index': False, 04:46:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:30 INFO - 'cmd': ('bash', 04:46:30 INFO - '-c', 04:46:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:30 INFO - 'enabled': True, 04:46:30 INFO - 'halt_on_failure': False, 04:46:30 INFO - 'name': 'check_screen_resolution'},), 04:46:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:30 INFO - 'cmd': ('bash', 04:46:30 INFO - '-c', 04:46:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:30 INFO - 'enabled': True, 04:46:30 INFO - 'halt_on_failure': False, 04:46:30 INFO - 'name': 'check_screen_resolution'},), 04:46:30 INFO - 'run_cmd_checks_enabled': True, 04:46:30 INFO - 'sps_profile': False, 04:46:30 INFO - 'sps_profile_interval': 0, 04:46:30 INFO - 'suite': 'dromaeojs', 04:46:30 INFO - 'system_bits': '32', 04:46:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:30 INFO - 'title': 't-yosemite-r7-0321', 04:46:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:30 INFO - 'use_talos_json': True, 04:46:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:30 INFO - 'work_dir': 'build'} 04:46:30 INFO - [mozharness: 2016-11-18 12:46:30.997394Z] Running clobber step. 04:46:30 INFO - Running pre-action listener: _resource_record_pre_action 04:46:30 INFO - Running main action method: clobber 04:46:30 INFO - rmtree: /builds/slave/test/build 04:46:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:33 INFO - Running post-action listener: _resource_record_post_action 04:46:33 INFO - [mozharness: 2016-11-18 12:46:33.364179Z] Finished clobber step (success) 04:46:33 INFO - [mozharness: 2016-11-18 12:46:33.364303Z] Running read-buildbot-config step. 04:46:33 INFO - Running pre-action listener: _resource_record_pre_action 04:46:33 INFO - Running main action method: read_buildbot_config 04:46:33 INFO - Using buildbot properties: 04:46:33 INFO - { 04:46:33 INFO - "project": "", 04:46:33 INFO - "product": "firefox", 04:46:33 INFO - "script_repo_revision": "production", 04:46:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:46:33 INFO - "repository": "", 04:46:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:46:33 INFO - "buildid": "20161118021428", 04:46:33 INFO - "pgo_build": "False", 04:46:33 INFO - "basedir": "/builds/slave/test", 04:46:33 INFO - "buildnumber": 26, 04:46:33 INFO - "slavename": "t-yosemite-r7-0321", 04:46:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:46:33 INFO - "platform": "yosemite_r7", 04:46:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:46:33 INFO - "branch": "mozilla-aurora", 04:46:33 INFO - "repo_path": "releases/mozilla-aurora", 04:46:33 INFO - "revision": "594e6d7a00aff726c2284e8a40bd0b03d63c7187", 04:46:33 INFO - "stage_platform": "macosx64", 04:46:33 INFO - "builduid": "db273267aa5c47ee9bbf26bf7c372bc2", 04:46:33 INFO - "slavebuilddir": "test" 04:46:33 INFO - } 04:46:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 04:46:33 INFO - Running post-action listener: _resource_record_post_action 04:46:33 INFO - [mozharness: 2016-11-18 12:46:33.367564Z] Finished read-buildbot-config step (success) 04:46:33 INFO - [mozharness: 2016-11-18 12:46:33.367694Z] Running download-and-extract step. 04:46:33 INFO - Running pre-action listener: _resource_record_pre_action 04:46:33 INFO - Running main action method: download_and_extract 04:46:33 INFO - mkdir: /builds/slave/test/build/tests 04:46:33 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')]} 04:46:33 INFO - trying https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 04:46:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 04:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 04:46:34 INFO - Downloaded 1199 bytes. 04:46:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 04:46:34 INFO - Using the following test package requirements: 04:46:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 04:46:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 04:46:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 04:46:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'jsshell-mac.zip'], 04:46:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 04:46:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 04:46:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 04:46:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 04:46:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 04:46:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 04:46:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 04:46:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:46:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 04:46:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:46:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 04:46:35 INFO - Http code: 200 04:46:35 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 04:46:35 INFO - x-amz-cf-id: 9bxNDGkforkOOp-Yem9-CWTKdNRQkTYElzxMXwY6u_lwT1PShF_87g== 04:46:35 INFO - x-amz-version-id: lO_U0GtjYUNZOeSx6HhH7pL5V0ZxstbA 04:46:35 INFO - Expected file size: 16274880 04:46:35 INFO - Obtained file size: 16274880 04:46:42 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:46:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 04:46:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:46:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 04:46:42 INFO - Http code: 200 04:46:42 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 04:46:42 INFO - x-amz-cf-id: BrVBCIulka2UVFlB4r9BjbKfZTzS_K47EGsoIbNXndXxWLf70FyzHA== 04:46:42 INFO - x-amz-version-id: lO_U0GtjYUNZOeSx6HhH7pL5V0ZxstbA 04:46:43 INFO - Expected file size: 16274880 04:46:43 INFO - Obtained file size: 16274880 04:46:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 04:46:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:46:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 04:46:49 INFO - Http code: 200 04:46:49 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 04:46:49 INFO - x-amz-cf-id: j09QGhvyu4Q8ogs5msgHiIFv6t7wbgQBbOGcSN17l-82JOXVYC302Q== 04:46:49 INFO - x-amz-version-id: YDK6hg8z5KXLPjTZUCMSe5ICk88k5890 04:46:50 INFO - Expected file size: 14409038 04:46:50 INFO - Obtained file size: 14409038 04:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:50 INFO - trying https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 04:46:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 04:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 04:46:53 INFO - Downloaded 114366663 bytes. 04:46:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 04:46:53 INFO - mkdir: /builds/slave/test/properties 04:46:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:53 INFO - Contents: 04:46:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 04:46:54 INFO - Running post-action listener: _resource_record_post_action 04:46:54 INFO - Running post-action listener: set_extra_try_arguments 04:46:54 INFO - [mozharness: 2016-11-18 12:46:54.566598Z] Finished download-and-extract step (success) 04:46:54 INFO - [mozharness: 2016-11-18 12:46:54.566730Z] Running populate-webroot step. 04:46:54 INFO - Running pre-action listener: _resource_record_pre_action 04:46:54 INFO - Running main action method: populate_webroot 04:46:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:54 INFO - '--tppagecycles', 04:46:54 INFO - '7'], 04:46:54 INFO - 'tests': ['tsvgm']}}, 04:46:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['damp', 'tps']}, 04:46:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'tests': ['damp', 'tps']}, 04:46:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['dromaeo_dom']}, 04:46:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['basic_compositor_video']}, 04:46:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:46:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['a11yr', 04:46:54 INFO - 'ts_paint', 04:46:54 INFO - 'tpaint', 04:46:54 INFO - 'sessionrestore', 04:46:54 INFO - 'sessionrestore_no_auto_restore', 04:46:54 INFO - 'tabpaint']}, 04:46:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['tsvgx', 04:46:54 INFO - 'tsvgr_opacity', 04:46:54 INFO - 'tart', 04:46:54 INFO - 'tscrollx', 04:46:54 INFO - 'cart']}, 04:46:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:54 INFO - 'tsvgr_opacity', 04:46:54 INFO - 'tart', 04:46:54 INFO - 'tscrollx', 04:46:54 INFO - 'cart']}, 04:46:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'talos_options': ['--disable-e10s'], 04:46:54 INFO - 'tests': ['tp5o']}, 04:46:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'tests': ['tp5o']}, 04:46:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'talos_options': ['--disable-e10s', 04:46:54 INFO - '--xperf_path', 04:46:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:54 INFO - 'tests': ['tp5n']}, 04:46:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:54 INFO - 'talos_options': ['--xperf_path', 04:46:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:54 INFO - 'tests': ['tp5n']}}, 04:46:54 INFO - 'talos.zip': {'path': '', 04:46:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:54 INFO - Running post-action listener: _resource_record_post_action 04:46:54 INFO - [mozharness: 2016-11-18 12:46:54.585426Z] Finished populate-webroot step (success) 04:46:54 INFO - [mozharness: 2016-11-18 12:46:54.585559Z] Running create-virtualenv step. 04:46:54 INFO - Running pre-action listener: _resource_record_pre_action 04:46:54 INFO - Running main action method: create_virtualenv 04:46:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:54 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 04:46:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:46:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:46:54 INFO - Using real prefix '/tools/python27' 04:46:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:46:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:46:58 INFO - Installing pip.................done. 04:46:58 INFO - Return code: 0 04:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:46:58 INFO - Reading from file tmpfile_stdout 04:46:58 INFO - Output received: 04:46:58 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) 04:46:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:58 INFO - 'HOME': '/Users/cltbld', 04:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:58 INFO - 'LOGNAME': 'cltbld', 04:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:58 INFO - 'MOZ_NO_REMOTE': '1', 04:46:58 INFO - 'NO_EM_RESTART': '1', 04:46:58 INFO - 'PAGER': '/bin/cat', 04:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:58 INFO - 'PWD': '/builds/slave/test', 04:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:58 INFO - 'SHELL': '/bin/bash', 04:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:46:58 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:58 INFO - 'USER': 'cltbld', 04:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:58 INFO - 'XPC_FLAGS': '0x0', 04:46:58 INFO - 'XPC_SERVICE_NAME': '0', 04:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:58 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 04:46:58 INFO - Cleaning up... 04:46:58 INFO - Return code: 0 04:46:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:58 INFO - 'HOME': '/Users/cltbld', 04:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:58 INFO - 'LOGNAME': 'cltbld', 04:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:58 INFO - 'MOZ_NO_REMOTE': '1', 04:46:58 INFO - 'NO_EM_RESTART': '1', 04:46:58 INFO - 'PAGER': '/bin/cat', 04:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:58 INFO - 'PWD': '/builds/slave/test', 04:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:58 INFO - 'SHELL': '/bin/bash', 04:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:46:58 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:58 INFO - 'USER': 'cltbld', 04:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:58 INFO - 'XPC_FLAGS': '0x0', 04:46:58 INFO - 'XPC_SERVICE_NAME': '0', 04:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:59 INFO - Downloading/unpacking psutil>=3.1.1 04:46:59 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 04:46:59 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 04:46:59 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 04:46:59 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 04:46:59 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 04:46:59 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 04:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:02 INFO - Installing collected packages: psutil 04:47:02 INFO - Running setup.py install for psutil 04:47:02 INFO - building 'psutil._psutil_osx' extension 04:47:02 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 04:47: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:47: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:47:03 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 04:47:03 INFO - building 'psutil._psutil_posix' extension 04:47:03 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 04:47:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:03 INFO - ^ 04:47:03 INFO - 1 warning generated. 04:47:03 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 04:47:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:03 INFO - Successfully installed psutil 04:47:03 INFO - Cleaning up... 04:47:03 INFO - Return code: 0 04:47:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47:03 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 04:47:03 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 04:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:03 INFO - 'HOME': '/Users/cltbld', 04:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:03 INFO - 'LOGNAME': 'cltbld', 04:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:03 INFO - 'MOZ_NO_REMOTE': '1', 04:47:03 INFO - 'NO_EM_RESTART': '1', 04:47:03 INFO - 'PAGER': '/bin/cat', 04:47:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:03 INFO - 'PWD': '/builds/slave/test', 04:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:03 INFO - 'SHELL': '/bin/bash', 04:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:03 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:03 INFO - 'USER': 'cltbld', 04:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:03 INFO - 'XPC_FLAGS': '0x0', 04:47:03 INFO - 'XPC_SERVICE_NAME': '0', 04:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:06 INFO - Installing collected packages: mozsystemmonitor 04:47:06 INFO - Running setup.py install for mozsystemmonitor 04:47:06 INFO - Successfully installed mozsystemmonitor 04:47:06 INFO - Cleaning up... 04:47:06 INFO - Return code: 0 04:47:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:06 INFO - 'HOME': '/Users/cltbld', 04:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:06 INFO - 'LOGNAME': 'cltbld', 04:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:06 INFO - 'MOZ_NO_REMOTE': '1', 04:47:06 INFO - 'NO_EM_RESTART': '1', 04:47:06 INFO - 'PAGER': '/bin/cat', 04:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:06 INFO - 'PWD': '/builds/slave/test', 04:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:06 INFO - 'SHELL': '/bin/bash', 04:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:06 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:06 INFO - 'USER': 'cltbld', 04:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:06 INFO - 'XPC_FLAGS': '0x0', 04:47:06 INFO - 'XPC_SERVICE_NAME': '0', 04:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:06 INFO - Downloading/unpacking jsonschema==2.5.1 04:47:06 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 04:47:06 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 04:47:06 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 04:47:06 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 04:47:06 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 04:47:06 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 04:47:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:10 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:10 INFO - warning: no files found matching '*.txt' 04:47:10 INFO - no previously-included directories found matching 'build' 04:47:10 INFO - no previously-included directories found matching 'dist' 04:47:10 INFO - no previously-included directories found matching '.git*' 04:47:10 INFO - Installing collected packages: jsonschema, functools32 04:47:10 INFO - Running setup.py install for functools32 04:47:10 INFO - warning: no files found matching '*.txt' 04:47:10 INFO - no previously-included directories found matching 'build' 04:47:10 INFO - no previously-included directories found matching 'dist' 04:47:10 INFO - no previously-included directories found matching '.git*' 04:47:10 INFO - Successfully installed jsonschema functools32 04:47:10 INFO - Cleaning up... 04:47:10 INFO - Return code: 0 04:47:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47:10 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')]} 04:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:10 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')]} 04:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:10 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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47:10 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 04:47:10 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 04:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:10 INFO - 'HOME': '/Users/cltbld', 04:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:10 INFO - 'LOGNAME': 'cltbld', 04:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:10 INFO - 'MOZ_NO_REMOTE': '1', 04:47:10 INFO - 'NO_EM_RESTART': '1', 04:47:10 INFO - 'PAGER': '/bin/cat', 04:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:10 INFO - 'PWD': '/builds/slave/test', 04:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:10 INFO - 'SHELL': '/bin/bash', 04:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:10 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:10 INFO - 'USER': 'cltbld', 04:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:10 INFO - 'XPC_FLAGS': '0x0', 04:47:10 INFO - 'XPC_SERVICE_NAME': '0', 04:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:11 INFO - Cleaning up... 04:47:11 INFO - Return code: 0 04:47:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47: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 04:47: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 04:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:11 INFO - 'HOME': '/Users/cltbld', 04:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:11 INFO - 'LOGNAME': 'cltbld', 04:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:11 INFO - 'MOZ_NO_REMOTE': '1', 04:47:11 INFO - 'NO_EM_RESTART': '1', 04:47:11 INFO - 'PAGER': '/bin/cat', 04:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:11 INFO - 'PWD': '/builds/slave/test', 04:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:11 INFO - 'SHELL': '/bin/bash', 04:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:11 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:11 INFO - 'USER': 'cltbld', 04:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:11 INFO - 'XPC_FLAGS': '0x0', 04:47:11 INFO - 'XPC_SERVICE_NAME': '0', 04:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:15 INFO - Downloading docopt-0.6.1.tar.gz 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:15 INFO - Running setup.py install for blobuploader 04:47:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:15 INFO - Running setup.py install for requests 04:47:16 INFO - Running setup.py install for docopt 04:47:16 INFO - Successfully installed blobuploader requests docopt 04:47:16 INFO - Cleaning up... 04:47:16 INFO - Return code: 0 04:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47: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 04:47: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 04:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:16 INFO - 'HOME': '/Users/cltbld', 04:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:16 INFO - 'LOGNAME': 'cltbld', 04:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:16 INFO - 'MOZ_NO_REMOTE': '1', 04:47:16 INFO - 'NO_EM_RESTART': '1', 04:47:16 INFO - 'PAGER': '/bin/cat', 04:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:16 INFO - 'PWD': '/builds/slave/test', 04:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:16 INFO - 'SHELL': '/bin/bash', 04:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:16 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:16 INFO - 'USER': 'cltbld', 04:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:16 INFO - 'XPC_FLAGS': '0x0', 04:47:16 INFO - 'XPC_SERVICE_NAME': '0', 04:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-6eg5jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-P4g0kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-hM_dUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-py3wtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-xgLpxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-a1XS0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-on3TKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-b6QA5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_nVGu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-t79agr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ZEcY5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VWJmRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Q1N6kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_Bkbgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:18 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-FAq2Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:18 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Xq8RZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:18 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wyLgbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:18 INFO - Running setup.py install for manifestparser 04:47:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozcrash 04:47:18 INFO - Running setup.py install for mozdebug 04:47:18 INFO - Running setup.py install for mozdevice 04:47:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozfile 04:47:19 INFO - Running setup.py install for mozhttpd 04:47:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozinfo 04:47:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozInstall 04:47:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozleak 04:47:19 INFO - Running setup.py install for mozlog 04:47:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for moznetwork 04:47:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozprocess 04:47:20 INFO - Running setup.py install for mozprofile 04:47:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Running setup.py install for mozrunner 04:47:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Running setup.py install for mozscreenshot 04:47:20 INFO - Running setup.py install for moztest 04:47:20 INFO - Running setup.py install for mozversion 04:47:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:20 INFO - Cleaning up... 04:47:20 INFO - Return code: 0 04:47:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:20 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')]} 04:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:20 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')]} 04:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:20 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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47:20 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 04:47:20 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 04:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:20 INFO - 'HOME': '/Users/cltbld', 04:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:20 INFO - 'LOGNAME': 'cltbld', 04:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:20 INFO - 'MOZ_NO_REMOTE': '1', 04:47:20 INFO - 'NO_EM_RESTART': '1', 04:47:20 INFO - 'PAGER': '/bin/cat', 04:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:20 INFO - 'PWD': '/builds/slave/test', 04:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:20 INFO - 'SHELL': '/bin/bash', 04:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:20 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:20 INFO - 'USER': 'cltbld', 04:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:20 INFO - 'XPC_FLAGS': '0x0', 04:47:20 INFO - 'XPC_SERVICE_NAME': '0', 04:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-BozvVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47: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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-hoDOMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-lCVcR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47: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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-KaW0Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47: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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-LcDo78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47: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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-yepECD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:21 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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-y4n7mA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:21 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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:21 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-yZUPTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-O9od46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-QXjxQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VA5Hxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-aXXYfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-OT1ESU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47: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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-RIoXKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-oxje_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:22 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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-5TPhcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:22 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)) 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-bRMuOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:22 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)) 04:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:22 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)) 04:47:22 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)) 04:47:22 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)) 04:47:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:22 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 04:47:22 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 04:47:22 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 04:47:22 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 04:47:22 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 04:47:22 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 04:47:25 INFO - Downloading blessings-1.6.tar.gz 04:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:25 INFO - Installing collected packages: blessings 04:47:25 INFO - Running setup.py install for blessings 04:47:25 INFO - Successfully installed blessings 04:47:25 INFO - Cleaning up... 04:47:25 INFO - Return code: 0 04:47:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:26 INFO - Reading from file tmpfile_stdout 04:47:26 INFO - Current package versions: 04:47:26 INFO - blessings == 1.6 04:47:26 INFO - blobuploader == 1.2.4 04:47:26 INFO - docopt == 0.6.1 04:47:26 INFO - functools32 == 3.2.3-2 04:47:26 INFO - jsonschema == 2.5.1 04:47:26 INFO - manifestparser == 1.1 04:47:26 INFO - mozInstall == 1.12 04:47:26 INFO - mozcrash == 1.0 04:47:26 INFO - mozdebug == 0.1 04:47:26 INFO - mozdevice == 0.48 04:47:26 INFO - mozfile == 1.2 04:47:26 INFO - mozhttpd == 0.7 04:47:26 INFO - mozinfo == 0.9 04:47:26 INFO - mozleak == 0.1 04:47:26 INFO - mozlog == 3.4 04:47:26 INFO - moznetwork == 0.27 04:47:26 INFO - mozprocess == 0.23 04:47:26 INFO - mozprofile == 0.28 04:47:26 INFO - mozrunner == 6.13 04:47:26 INFO - mozscreenshot == 0.1 04:47:26 INFO - mozsystemmonitor == 0.3 04:47:26 INFO - moztest == 0.7 04:47:26 INFO - mozversion == 1.4 04:47:26 INFO - psutil == 3.1.1 04:47:26 INFO - requests == 1.2.3 04:47:26 INFO - wsgiref == 0.1.2 04:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47: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 04:47: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 04:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:26 INFO - 'HOME': '/Users/cltbld', 04:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:26 INFO - 'LOGNAME': 'cltbld', 04:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:26 INFO - 'MOZ_NO_REMOTE': '1', 04:47:26 INFO - 'NO_EM_RESTART': '1', 04:47:26 INFO - 'PAGER': '/bin/cat', 04:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:26 INFO - 'PWD': '/builds/slave/test', 04:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:26 INFO - 'SHELL': '/bin/bash', 04:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:26 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:26 INFO - 'USER': 'cltbld', 04:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:26 INFO - 'XPC_FLAGS': '0x0', 04:47:26 INFO - 'XPC_SERVICE_NAME': '0', 04:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47:27 INFO - Cleaning up... 04:47:27 INFO - Return code: 0 04:47:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x108886490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dca4d2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, '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.QWiylcnWJA/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.UeGgqmvw4n/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:47: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 04:47: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 04:47:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:47:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:27 INFO - 'HOME': '/Users/cltbld', 04:47:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:27 INFO - 'LOGNAME': 'cltbld', 04:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:27 INFO - 'MOZ_NO_REMOTE': '1', 04:47:27 INFO - 'NO_EM_RESTART': '1', 04:47:27 INFO - 'PAGER': '/bin/cat', 04:47:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:27 INFO - 'PWD': '/builds/slave/test', 04:47:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:27 INFO - 'SHELL': '/bin/bash', 04:47:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:47:27 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:47:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:27 INFO - 'USER': 'cltbld', 04:47:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:27 INFO - 'XPC_FLAGS': '0x0', 04:47:27 INFO - 'XPC_SERVICE_NAME': '0', 04:47:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:47:27 INFO - Cleaning up... 04:47:27 INFO - Return code: 0 04:47:27 INFO - Running post-action listener: _resource_record_post_action 04:47:27 INFO - Running post-action listener: _start_resource_monitoring 04:47:27 INFO - Starting resource monitoring. 04:47:27 INFO - [mozharness: 2016-11-18 12:47:27.941594Z] Finished create-virtualenv step (success) 04:47:27 INFO - [mozharness: 2016-11-18 12:47:27.942577Z] Running install step. 04:47:27 INFO - Running pre-action listener: _resource_record_pre_action 04:47:27 INFO - Running main action method: install 04:47:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:28 INFO - Reading from file tmpfile_stdout 04:47:28 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:28 INFO - Reading from file tmpfile_stdout 04:47:28 INFO - Output received: 04:47:28 INFO - Usage: mozinstall [options] installer 04:47:28 INFO - Options: 04:47:28 INFO - -h, --help show this help message and exit 04:47:28 INFO - -d DEST, --destination=DEST 04:47:28 INFO - Directory to install application into. [default: 04:47:28 INFO - "/builds/slave/test"] 04:47:28 INFO - --app=APP Application being installed. [default: firefox] 04:47:28 INFO - mkdir: /builds/slave/test/build/application 04:47:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:01 INFO - Reading from file tmpfile_stdout 04:48:01 INFO - Output received: 04:48:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:48:01 INFO - Running post-action listener: _resource_record_post_action 04:48:01 INFO - [mozharness: 2016-11-18 12:48:01.017734Z] Finished install step (success) 04:48:01 INFO - [mozharness: 2016-11-18 12:48:01.017908Z] Running run-tests step. 04:48:01 INFO - Running pre-action listener: _resource_record_pre_action 04:48:01 INFO - Running main action method: run_tests 04:48:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:01 INFO - 2016-11-18 04:48:01.032 screenresolution[1626:7424] starting screenresolution argv=screenresolution get 04:48:01 INFO - 2016-11-18 04:48:01.035 screenresolution[1626:7424] Display 0: 1600x1200x32@60 04:48:01 INFO - 2016-11-18 04:48:01.040 screenresolution[1627:7426] starting screenresolution argv=screenresolution list 04:48:01 INFO - Available Modes on Display 0 04:48:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:01 INFO - Intel Iris: 04:48:01 INFO - Chipset Model: Intel Iris 04:48:01 INFO - Type: GPU 04:48:01 INFO - Bus: Built-In 04:48:01 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:01 INFO - Vendor: Intel (0x8086) 04:48:01 INFO - Device ID: 0x0a2e 04:48:01 INFO - Revision ID: 0x0009 04:48:01 INFO - Displays: 04:48:01 INFO - Display: 04:48:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:01 INFO - Display Serial Number: 1600x1200 60 04:48:01 INFO - Main Display: Yes 04:48:01 INFO - Mirror: Off 04:48:01 INFO - Online: Yes 04:48:01 INFO - Rotation: Supported 04:48:01 INFO - Return code: 0 04:48:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:01 INFO - Python 2.7.3 04:48:01 INFO - Return code: 0 04:48:01 INFO - grabbing minidump binary from tooltool 04:48:01 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')]} 04:48:01 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 0x10869ad30>, '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 0x108500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108692b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:01 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 04:48:01 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 04:48:01 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 04:48:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:01 INFO - Return code: 0 04:48:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 04:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 04:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QWiylcnWJA/Render', 04:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:01 INFO - 'HOME': '/Users/cltbld', 04:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:01 INFO - 'LOGNAME': 'cltbld', 04:48:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:01 INFO - 'MOZ_NO_REMOTE': '1', 04:48:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:01 INFO - 'NO_EM_RESTART': '1', 04:48:01 INFO - 'PAGER': '/bin/cat', 04:48:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:01 INFO - 'PWD': '/builds/slave/test', 04:48:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:01 INFO - 'SHELL': '/bin/bash', 04:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UeGgqmvw4n/Listeners', 04:48:01 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:01 INFO - 'USER': 'cltbld', 04:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:01 INFO - 'XPC_FLAGS': '0x0', 04:48:01 INFO - 'XPC_SERVICE_NAME': '0', 04:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 04:48:01 INFO - mozversion application_buildid: 20161118021428 04:48:01 INFO - mozversion application_changeset: 594e6d7a00aff726c2284e8a40bd0b03d63c7187 04:48:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:48:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:01 INFO - mozversion application_name: Firefox 04:48:01 INFO - mozversion application_remotingname: firefox-dev 04:48:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:48:01 INFO - mozversion application_vendor: Mozilla 04:48:01 INFO - mozversion application_version: 52.0a2 04:48:01 INFO - mozversion platform_buildid: 20161118021428 04:48:01 INFO - mozversion platform_changeset: 594e6d7a00aff726c2284e8a40bd0b03d63c7187 04:48:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:48:01 INFO - mozversion platform_version: 52.0a2 04:48:01 INFO - using testdate: 1479473281 04:48:01 INFO - actual date: 1479473281 04:48:01 INFO - starting webserver on 'localhost:49241' 04:48:01 INFO - SUITE-START | Running 2 tests 04:48:01 INFO - TEST-START | dromaeo_css 04:48:01 INFO - operating with platform_type : mac_ 04:48:01 INFO - Initialising browser for dromaeo_css test... 04:48:01 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpbHWrcn/profile http://localhost:49241/getInfo.html) 04:48:03 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 04:48:03 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 04:48:03 INFO - PROCESS | 1637 | colorDepth:24 04:48:03 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 04:48:03 INFO - PROCESS | 1637 | __metrics 04:48:04 INFO - PROCESS | 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:04 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:04 INFO - TEST-INFO | 1637: exit 0 04:48:04 INFO - Browser initialized. 04:48:04 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpbHWrcn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:16 INFO - PROCESS | 1639 | RSS: Main: 213516288 04:48:16 INFO - PROCESS | 1639 | 04:49:07 INFO - PROCESS | 1639 | 1479473347893 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] 04:51:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:51:24 INFO - PROCESS | 1639 | RSS: Main: 414064640 04:51:24 INFO - PROCESS | 1639 | 04:54:27 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:54:28 INFO - PROCESS | 1639 | RSS: Main: 350355456 04:54:28 INFO - PROCESS | 1639 | 04:57:30 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:57:31 INFO - PROCESS | 1639 | RSS: Main: 319401984 04:57:31 INFO - PROCESS | 1639 | 05:00:33 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:00:34 INFO - PROCESS | 1639 | RSS: Main: 328450048 05:00:34 INFO - PROCESS | 1639 | 05:03:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:03:38 INFO - PROCESS | 1639 | RSS: Main: 310972416 05:03:38 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:06:41 INFO - PROCESS | 1639 | RSS: Main: 357957632 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | __start_tp_report 05:06:41 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 05:06:41 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 05:06:41 INFO - PROCESS | 1639 | |i|pagename|runs| 05:06:41 INFO - PROCESS | 1639 | |0;dojo.html;3332.5501057357255;3333.1833700743305;3339.7829141105826;3370.325934813035;3395.507651390547;4983.676061056029;5260.447652996438;5577.665340079598;5616.185653080312;5690.260266165403;5639.182318563806;5678.091505359143;5680.829575112753;5709.143628455734;5726.427357264271;5155.7679904404295;5164.638475306711;5184.662996905189;5219.329814459375;5235.083860324447;5125.256837758523;5130.256112863633;5151.845444636667;5304.071787437202;5332.440093790155;4592.862214133581;4909.116359055369;5230.686158830464;5236.057509648262;5239.685618862881;5233.712145831978;5239.16173412256;5240.371155461331;5247;5261.026710058652;5136.101182293121;5167.379914410295;5192.532672059495;5196.0647083524955;5204.791808327663;5702.572307076985;5710.172025056385;5717.056685646863;5717.542596592262;5721;6118.040554665137;6131.499004853489;6134.234418595382;6136.6011209271255;6140.381550880938;6211.503079753609;6229.844253893643;6243.406876346739;6243.56295059346;6308.022256550242;4843.854684359475;5356.892862142735;5760.135979603051;5799.971000145015;5827.067669172912;6251.6561589113035;6290.182276304143;6302.810915672537;6317.684115794192;6341.397567231105;6047.334793172748;6059.939400606026;6095.878082438415;6101.816945491553;6108.076676490625;6258.499320054385;6264.498840092781;6286.937130628727;6310.1796766420075;6310.5898116622275;6236.968815155895;6239.656818874914;6258.186435763413;6265.8120256392385;6267.905981410283;6057.061155520902;6124.960759790135;6129.540284478593;6140.078988151813;6144.494598823317;4950.2822090796635;4968.30443737877;4975.477574854588;4996.400431948189;4998.525140111684;3916.236333914916;4430.291153415438;4431.578999994996;4461.174682683714;4473.977630111829;5642.351129620146;5728.1694154348215;5872.618279811799;5882.676452795137;5919.378465261172;6187.504999600022;6222.222222222223;6271.560990730605;6295.087212354183;6329.430351268408;5828.329742079715;5829.038208695518;5838.9124163137585;5848.61983971049;5854.414558544111;4596.977015114903;5358.678479291255;5398.865028374322;5424.647397919122;5427.48438898304;5882.970585147047;6113.476306051939;6226.346233645583;6229.688515574113;6230;6309.716062777278;6320.273168585672;6337.4613157882095;6342.080398342307;6345.428911397812;6324.905126423016;6336.017917222838;6336.144620476177;6348.936510634834;6355.968220158869;6108.4807791338235;6120.447133218882;6140.140380346845;6174.969125154345;6232.657203853832;6126.639818174547;6233.625982441068;6234.812955611339;6283.183186185766;6297.4647154992335;6102.633841969496;6117.082437634391;6119;6132.08018797184;6144;5869.501092407193;6013.488853447506;6121.418465245794;6138.07928810682;6144.201253836973;5819.098039803837;5871.735771890189;5898.616589921813;5942.168096466585;5942.435468630473;6144.832481828438;6151.5386346025125;6164.229471316085;6164.537659675632;6173.59871608344;5797.8550536236935;5827.59206855516;5844.327902291291;5846.152307915243;5854.268216472941 05:06:41 INFO - PROCESS | 1639 | |1;ext.html;459.3637811630894;501.593031546512;506.74327667384887;515.4948150812204;552.6711606594077;28624.141275761733;38728.612713872855;39888.202235955265;40036.39945400821;41785.7910710446;40644.59355406445;40789;41115.58884411155;41122.38316425256;41140.58859411405;8467.364947628921;9308.301877359208;9499.382540134904;9504.477253751042;9509.239260859114;8466.872996905051;11138.888611113907;11419.371934543597;11449.484773185184;11635.24370915892;11195.720106997349;11323.886761132408;11341.206115571913;11345.546178152865;11353.886461135407;11295.096392288597;11368.772624547546;11381.715457113556;11384.715382115513;11403.3728144952;11086.667399978014;11306.717332066683;11307;11309.321440713584;11319.943400282946;38932;39157.80421097905;39266;39280;39311.47917989439;3167.1448708848484;3231.353729254135;3411.266577685785;3519.454484554898;3576.409892367782;29964.8501757492;30036.948706795156;30291.545626815616;30643.387132257452;31060.068197954097;3151.243701511644;3165.683431656839;3207.695268949446;3223.903282901517;3252.08941496379;423359;426360;427943;431055;431571;32732;44552.554474455486;53012.20481692778;54061.1890821638;54280.45719542754;33589.83205083959;34600.65399346025;34642.82678586591;34666.9599912003;34725.30549389048;14048.648783780487;14180.432782688576;14191.432342706175;14203.502877399447;14268.57194284173;10871.565137394573;11053.778924421467;11933.224340417844;12036.81944770829;12053;7412.851008093754;7514.736984205527;7700.537967721954;7701.191374905667;7701.383889288843;2490.7384724603658;3164.1298642873394;3166.3983843070205;3186.3786561620263;3199.696028877253;884.1909652667865;920.6593560382568;945.976350591227;1048.9003544663244;1105.40308233553;29251.853740731585;29283.707162928524;29564.704352956625;29936.700632993827;29998.95003674861;1000.5997600959674;1046.3564907581783;1066.7973085113804;1081.4106312059946;1102.818035024228;12436.37818109091;14904.329305181293;15181.316840741973;15278.541643750705;15706;673592;678905;679383;680951;681087.594562024;56838.43161568414;57390.139147912814;57525;57597.42402576005;57838;1205.3671822293365;1233.173773571691;1317.6771690935782;1342.5234041915176;1351.8175046368615;28922;30181.849090754407;30493.54259686062;30654.55834410914;30910.845445772626;48584.514154858;49122;49178.262326064425;49246.75376623094;49317.50682493129;528923;530633;531169;532988;534508;22738.31785046451;27592.862035689694;27603;27640.032598858943;27954.440911181257;44070.118597627225;44097;44397;44656;45253.09493810171;33209.33581328311;36130.63869361273;37823;38090.80954595209;38674;37173;38057.858264252114;38066.42900356552;40020.599794002854;40177.39733903935;37537.37238393286;37617.4357384634;37767.43348849715;37786.43320350142;38464.6153538461;32924.50613240755;33591.32817343689;34494.82752586321;34562;34709;20358.898205508878;25265.62101568515;25462.618060728735;25477;26051.218463446126 05:06:41 INFO - PROCESS | 1639 | |2;jquery.html;351.7397126126667;368.22672387985233;371.64693541135927;384.14336030651657;401.1435585025969;14670;14823.925880370596;15641.687166256668;19634.312799052037;20327.796722032774;18863.905680471613;19061.52346191346;19824.107915143817;19854.20583176671;19918.402447926586;5551.361593416758;5844.824655260349;5858.472737453629;5860.648361098327;5862.032764593858;5346.545543628797;5667.319921609392;5706.572007099463;5707.885842283173;5710.486056254937;5609.1305847593685;5692.459216374438;5693.829185124432;5698.43015698431;5701.77192912283;4398.3842262083335;5680.886382272352;5737.39757325479;5744.856378590527;5748.396418376052;4789.161896668104;5025.773840177201;5032.547070763612;5039.52124548171;5041.9747901260625;19857.503562411057;24090.879545602158;24416.389590260387;24647.630285545732;24724.629130563055;2143.3141394753684;2246.1240116354634;2422.5760491913843;2443.8900249488815;2925;14125.576232713034;14300.070495417765;14752.262386880613;15213.847861521464;15283;1778.0665150795935;1789.8031216566167;1833.1475863723433;1841.3647291684347;1897.9240830366768;120090.7990920097;124216;128465.99999999907;130883;132376;9228.907710922806;11129.944350278196;11524;11533.942330288295;11630;14486.710265794836;14522.273886305642;14525.418983240552;14533.418663253562;14558.199299038655;6991.440684745208;7990.20097990208;8197.50814951093;8258.091609922903;8383.203595658402;8081.676732930617;8161.143079976636;8202.343812494986;8338.583070846555;8342.332613390914;4372.103718737651;4387.100644367897;4387.978060109743;4396.07682386696;4465.620422264079;1737.991310043467;1828.4240464253717;1885.6040231551253;1891.297831951255;1895.6587814193301;723.2731105239307;789.8657228271207;790.8966991047456;905.4160066757037;977.5014742481386;20253.594928101356;20954.161833526792;21027.05378257982;21065.578688426453;21152.78847211508;911.2755359489222;918.9908100919039;936.4849332866896;968.6464440479315;973.0221127766691;10441.425721585385;10970.396628185526;11000.944995274973;11035;11043.944780276048;600529;613333.9333303305;615824;627080;629291;102175;105899;106093.46953265338;106121;106925;946.8911075226437;950.0499500499501;951.481442613791;972.0522490571777;978.5351957819978;17310;17509.737353939447;17778.644427111645;18156.27374904989;18282.360117395827;42921.35617965795;50422;51451;53765.73117134389;54679.17981230205;69176.6541167291;69462;72993.63503182662;73218.63390683013;74008.25991740014;7555.244475552401;7770.950921625508;7813.570253636105;7986.84026319482;8067.556284404414;9331.440113593206;9711;11805.645830625095;12300.569480068154;12301.32342721159;10618.946905265424;10903.836442453528;11096;11137.944310278397;11298.09615230796;9342.579583918605;9987.850182247417;10664.040236378763;10752.892471075502;11207.327560346404;9056.954715226382;11006.119510438986;11082;11505.309681419141;11515.596954106566;6956.895646565406;7231.096112985877;7322.3775979042375;7478.887816682645;8069.636866340911;9390.248780097712;10887.455627218766;11116.27744196657;11212.66362009141;11316.773664526829 05:06:41 INFO - PROCESS | 1639 | |3;mootools.html;1365.1536047650459;1442.3725679329493;1595.35388167792;1757.1302205408308;1803.7294405839123;8199.75400737978;8616.31069514439;8617.439866408678;8626.094260102693;8627.698030568932;7370.59461729606;7497.73757918473;7500.624968751568;7736.226377362261;7739.690412383512;2101.590189912964;2134.4770531219797;2275.590393729128;2357.1043003658588;2359.6460530920413;2385.9045638174453;2386.260259319608;2386.6658667786523;2389.032441861049;2391.9162829300976;2302.4013756423283;2358.50471401006;2361.090979972714;2367.573836709392;2370.0519792083132;2327;2334;2361.504084142332;2361.8464799787957;2362.1850461590725;2001.35956493922;2013.68103891951;2358.092134528219;2362.9409264768346;2382.1305223593436;8497.362697797627;8552.401331906769;8608.612612432456;8631.180037896389;8636.870446943238;5141.948580514184;5311.49540793624;5312.893742125136;5326.36083669962;5330.733463326818;3327.2513684421056;3501.124718820295;3598.6941110005682;3655.853765849363;3663.358912190356;933.6965486217007;998.8651532043153;1044.4986406524836;1118.401655114518;1122.5565901468956;11220;11306.321620702784;11308.660740177807;11317.886821131766;11326.263792853439;4492.2363198255725;4709.8351557696005;4787.54518320759;4822.348982887335;4828.879278018007;9590.712278631652;9615.471149086867;9620;9627.085426884434;9631.903680963242;5283.709395983258;5285.286486324374;5290.603204759658;5293.417724050351;5294.49702278283;4583.243764778841;4847.903041939141;4855.393075865517;4865.635077369141;4890.559849613552;3499.9825000874835;3640.5995340512522;3647.0306034740943;3748.269087527906;3913.726039177287;1230.194222784078;1236.5981056156786;1242.1553343726353;1243.5461056714414;1262.5202423079172;877.7322916510398;880.0847118996301;886.782738229138;894.088030209183;894.5303715549388;5322.866928326745;5542;5544.445555444423;5570.6657600544095;5660.433956604389;860.5697151424288;886.782738229138;922.0456827183832;931.2456909903;943.9858402123974;3418.447107868711;3759.285735710206;3878.108035151931;3896.2207558488417;3916.3342231820657;9909.554070066864;10234.437105959094;10410.323328983592;10425.478726063666;10531.368117912949;10405.791884162274;10465.738356541147;10512.42181680015;10522.473876306154;10523;7982.600869956479;8181.18188181189;8204.933358663335;8209.302209312274;8214.4660597061;5457.372402173801;5555.527780138734;5577.972110139423;5661;5667.40492248308;9563.760905977406;9613.423194608346;9711.757206069622;9721.902780972383;9726.902730972599;9472.384295021076;9484.383515071499;9762.902370976199;9882.160016398686;10036.447995360326;6689.264180939996;6834.111565496453;6840.452763778985;6846.418054465227;6848.315168483112;7084.716611335489;7356.337929586363;7357.043584334002;7461.216572260052;7969.72105976288;6572.967135164294;7012.509124361246;7233;7278.781636550912;7287.599182045038;7030.789076327718;7122.964385178248;7168.784936451915;7172.928270717226;7214.783556493314;6698.095757072928;6841.281665425058;6844.93155068443;6845.623490708059;6858.554193977376;4793.640476964172;4823.95176048235;4881.585065269492;4935.235038569028;5023.874403139951;4755.809767609257;4824;4860.465348811557;4951.356323678043;5046.369203849519 05:06:41 INFO - PROCESS | 1639 | |4;prototype.html;260.67676081658743;302.2564491351276;302.571860816944;313.19821257580594;317.04885343968095;5032.949670503293;5154.4072431670365;5222.764975576099;5256.211568264762;5263.578913686905;4638.3970083889135;5054.393472783262;5135.948640513594;5149.639525233235;5193.714345710984;3641.563012438498;3774.811259437031;4041.252368311857;4050.432939388488;4098.323776576876;3233.8059716416974;4045.2314060328445;4055.472788537486;4058.3506638937774;4069.674426045924;3816.313063648542;3978.860739874105;4011.7793521356316;4027.932597861569;4072.8370865165357;3831.386978083521;4020.8190631421658;4023.76680942077;4028.194361127786;4034.5763694812063;3232.6282477514915;3327.8336083195745;3500.3349363621087;3517.5427194464555;3525.1363415962996;4152.418661387408;4214.346776249687;4221.936670949938;5141.203113517411;5150.381954165524;4531.3429552715;4565.497795242521;4595.805090676415;4618.307253911907;4627.8148874045;5512.476314750093;5513.338399392099;5533.501984821345;5544;5577.776888924438;3714.739968202227;3871.4967054283043;3893.8831835044994;3896.1428485733095;3904.863329783444;4674.555917187861;4736.550027747358;5132.820351287687;5764.561287808406;5773.076307790733;3883.194314643648;3926.234384295035;3937.5668676445566;3947.842086316515;3961.2473630010204;3395.2021275000357;3996.9200615987515;4007.91984160321;4015.3374693175297;4024.3158663027357;3801.9734671639058;4200.85297014603;4335.653147748172;4609.285560738091;4660.997885454643;5570.554355651539;5577.274954256002;5577.776888924479;5584;5595.328560572676;1152.4468255237366;1192.8151136573845;1197.9221350612183;1239.7458521003352;1264.6522206393315;753.650964773061;761.5012167030604;793.2701914238914;807.6471909551477;824.2993455562844;3958.3072962231495;4053.966238609136;4634.304854271819;4792.880177995508;4815.109204797123;5225.817096401608;5229.267902493654;5229.50319719626;5264.578833693296;5267.683938963674;3984.2828290907337;4316.6546676265825;5234.398044224963;5268.262443257947;5317.813876514303;2373.774491423312;2545.879812882326;2551.813406765863;2804.9859750701116;2825.7598104160966;4568.949141697428;5518.862028449241;5597.460698307917;5636.436356364331;5639.080838817064;4906.460289368167;5091.465396133352;5473;5856.297244330707;5966.134910437962;5466.945330546723;5920.2007728956905;5927.288725352899;5945.554083443845;6121.540884433596;5514.310711161105;5721.654121810038;5761.798337058183;5791.220214297076;5920.526357891272;4470.485894122217;4910.207774162476;5182.590095605561;5619.269494965628;5668.319801623831;5041.722705251246;5499.532539734168;5901.793437229677;5979.611325263844;6042.607230530001;3095.7059079387423;3276.45938420158;3806.885793426202;3823.866164684223;3860.9227815442973;5358.758855851574;5742.856428589319;5745.3105627324985;5754.597178197486;5767.826965191051;4619.399478067964;4878.438979517401;5461.235427040297;5674.319081710221;5675.631083979528;4659.4623774155125;5448.945510544844;5539;5587;5607.214989901336;4379.518252992232;5051.772670229775;5108.79564817418;5226.398964119175;5535.335759708861;4769.2369220925875;5063.974680126576;5151.960343397413;5351.143816989263;5551.9722401387735;4873.905919028033;5369.4093649697725;5405.567554595545;5416.729163541886;5498.202760599771 05:06:41 INFO - PROCESS | 1639 | |5;yui.html;727.6870945493439;735.2059775442523;757.7613051888654;774.206438400639;792.4254915186486;15754.606134846634;16401.343946242152;16420.343186272563;16436.91781541092;16847.66304673909;15039.624009399771;15106.773398399006;15118.319675614597;15153.54539363818;15205.619859503518;648.2739331948225;669.4945316286221;813.7070654564352;840.9873851892196;844.2612713875359;804.74650485097;824.9752507424787;832.4838600067966;840.3613253927028;840.8738689196639;873.0134947509306;876.842168409686;877.5875338590884;880.1594477274219;880.8150288439435;680.7277089164324;871.6687658689657;872.8603423452249;873.9868901966476;874.4796845876693;518.380535260364;619.9132121502993;637.9919726831595;643.4980715042275;644.555256872761;13135.686431356788;13430.328483575782;14394.424223031067;14435.85564144366;14447.85552144486;13238.27189504577;13552.457901683922;13563.525276615272;13586.86413135866;13618.863811361858;12099.395030248541;12107.878921210764;12210.206336588093;12245.510179592806;12705.682357941125;4433.8448154314765;4466.57567531084;4471;4484.439445069366;4603.8158473661015;81340;84606;84940;85289.57355213247;85547.57226213891;9632.844058712999;10471.42219798747;10557.049865512141;10652.30760000613;10737.141028717684;19623.11695973656;20354.898225509074;20386.69419958702;20396;20439.284625038053;7215;7470.85058298831;7577.469577129548;7611.733589324347;7702.576358300347;4123.319652257344;4206.242876282237;4227.915441691149;4252.29837076885;4332.458442694663;3230.0632816483426;3333.7333013358875;3334.699877011081;3335.533025376449;3341.4486609709124;618.7432215630463;627.6391075131818;642.9003504456816;643.5591619740493;647.252423450915;541.3260490689039;586.4018700925118;605.461139585769;614.3487902823022;615.7044618583016;8354.373421993374;8401.747947561584;8518.403711740242;8638.09300023501;8702.825943481095;507.7689651208691;594.3699678340972;604.2356419129804;614.6496496996758;643.0611307491022;4188.748675079505;4209.368594710757;4234.237837189274;4237.491501019897;4307.375430562551;112320;115873;117558;118878;120884;64559.12645241206;66167;92077.53961230151;94664;97771;89510.55244723865;91103.99999999868;91767;91997;92649;6805.740937926499;8049.114597394165;8123.065847427385;8129.552874591717;8477.74566762998;44122.77938610286;57597.712011439675;58581.4141858576;59503;59668.43647198718;78904;82147;84089;84754;85701.57149214463;10476.895231047896;10734;10831.945840270748;10852.565897364017;11340.659780206606;10390.272680912263;10838.078763304891;11391.430428478378;11489.310641361544;11687.532498699957;9026.323025772961;9111.498867562348;12391.814122787984;12586.622401327975;12991.41538630745;12222.633321000385;12380.690482738004;12815;12990.610281691565;13179.67050823737;10866.239363244813;12152.21010634306;12245.693857653274;12492.687682807638;12529.89715379093;10066.295359324777;10486.475676216007;10626.149908007495;10997.505112270128;11016.449177540931;7253.601051942193;7447.5531468112085;8684.131586841266;8828.95585522094;9334.859977100485 05:06:41 INFO - PROCESS | 1639 | __end_tp_report 05:06:41 INFO - PROCESS | 1639 | __start_cc_report 05:06:41 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,2411 05:06:41 INFO - PROCESS | 1639 | __end_cc_report 05:06:41 INFO - PROCESS | 1639 | __startTimestamp1479474401717__endTimestamp 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | ------- Summary: start ------- 05:06:41 INFO - PROCESS | 1639 | Number of tests: 6 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5718.93 Median:5882.97 stddev:660.63 (11.2%) stddev-sans-first:635.58 05:06:41 INFO - PROCESS | 1639 | Values: 3332.6 3333.2 3339.8 3370.3 3395.5 4983.7 5260.4 5577.7 5616.2 5690.3 5639.2 5678.1 5680.8 5709.1 5726.4 5155.8 5164.6 5184.7 5219.3 5235.1 5125.3 5130.3 5151.8 5304.1 5332.4 4592.9 4909.1 5230.7 5236.1 5239.7 5233.7 5239.2 5240.4 5247.0 5261.0 5136.1 5167.4 5192.5 5196.1 5204.8 5702.6 5710.2 5717.1 5717.5 5721.0 6118.0 6131.5 6134.2 6136.6 6140.4 6211.5 6229.8 6243.4 6243.6 6308.0 4843.9 5356.9 5760.1 5800.0 5827.1 6251.7 6290.2 6302.8 6317.7 6341.4 6047.3 6059.9 6095.9 6101.8 6108.1 6258.5 6264.5 6286.9 6310.2 6310.6 6237.0 6239.7 6258.2 6265.8 6267.9 6057.1 6125.0 6129.5 6140.1 6144.5 4950.3 4968.3 4975.5 4996.4 4998.5 3916.2 4430.3 4431.6 4461.2 4474.0 5642.4 5728.2 5872.6 5882.7 5919.4 6187.5 6222.2 6271.6 6295.1 6329.4 5828.3 5829.0 5838.9 5848.6 5854.4 4597.0 5358.7 5398.9 5424.6 5427.5 5883.0 6113.5 6226.3 6229.7 6230.0 6309.7 6320.3 6337.5 6342.1 6345.4 6324.9 6336.0 6336.1 6348.9 6356.0 6108.5 6120.4 6140.1 6175.0 6232.7 6126.6 6233.6 6234.8 6283.2 6297.5 6102.6 6117.1 6119.0 6132.1 6144.0 5869.5 6013.5 6121.4 6138.1 6144.2 5819.1 5871.7 5898.6 5942.2 5942.4 6144.8 6151.5 6164.2 6164.5 6173.6 5797.9 5827.6 5844.3 5846.2 5854.3 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:66501.37 Median:34528.41 stddev:148929.93 (431.3%) stddev-sans-first:149265.18 05:06:41 INFO - PROCESS | 1639 | Values: 459.4 501.6 506.7 515.5 552.7 28624.1 38728.6 39888.2 40036.4 41785.8 40644.6 40789.0 41115.6 41122.4 41140.6 8467.4 9308.3 9499.4 9504.5 9509.2 8466.9 11138.9 11419.4 11449.5 11635.2 11195.7 11323.9 11341.2 11345.5 11353.9 11295.1 11368.8 11381.7 11384.7 11403.4 11086.7 11306.7 11307.0 11309.3 11319.9 38932.0 39157.8 39266.0 39280.0 39311.5 3167.1 3231.4 3411.3 3519.5 3576.4 29964.9 30036.9 30291.5 30643.4 31060.1 3151.2 3165.7 3207.7 3223.9 3252.1 423359.0 426360.0 427943.0 431055.0 431571.0 32732.0 44552.6 53012.2 54061.2 54280.5 33589.8 34600.7 34642.8 34667.0 34725.3 14048.6 14180.4 14191.4 14203.5 14268.6 10871.6 11053.8 11933.2 12036.8 12053.0 7412.9 7514.7 7700.5 7701.2 7701.4 2490.7 3164.1 3166.4 3186.4 3199.7 884.2 920.7 946.0 1048.9 1105.4 29251.9 29283.7 29564.7 29936.7 29999.0 1000.6 1046.4 1066.8 1081.4 1102.8 12436.4 14904.3 15181.3 15278.5 15706.0 673592.0 678905.0 679383.0 680951.0 681087.6 56838.4 57390.1 57525.0 57597.4 57838.0 1205.4 1233.2 1317.7 1342.5 1351.8 28922.0 30181.8 30493.5 30654.6 30910.8 48584.5 49122.0 49178.3 49246.8 49317.5 528923.0 530633.0 531169.0 532988.0 534508.0 22738.3 27592.9 27603.0 27640.0 27954.4 44070.1 44097.0 44397.0 44656.0 45253.1 33209.3 36130.6 37823.0 38090.8 38674.0 37173.0 38057.9 38066.4 40020.6 40177.4 37537.4 37617.4 37767.4 37786.4 38464.6 32924.5 33591.3 34494.8 34562.0 34709.0 20358.9 25265.6 25462.6 25477.0 26051.2 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34692.93 Median:377.90 stddev:102547.24 (27136.4%) stddev-sans-first:102802.50 05:06:41 INFO - PROCESS | 1639 | Values: 351.7 368.2 371.6 384.1 401.1 14670.0 14823.9 15641.7 19634.3 20327.8 18863.9 19061.5 19824.1 19854.2 19918.4 5551.4 5844.8 5858.5 5860.6 5862.0 5346.5 5667.3 5706.6 5707.9 5710.5 5609.1 5692.5 5693.8 5698.4 5701.8 4398.4 5680.9 5737.4 5744.9 5748.4 4789.2 5025.8 5032.5 5039.5 5042.0 19857.5 24090.9 24416.4 24647.6 24724.6 2143.3 2246.1 2422.6 2443.9 2925.0 14125.6 14300.1 14752.3 15213.8 15283.0 1778.1 1789.8 1833.1 1841.4 1897.9 120090.8 124216.0 128466.0 130883.0 132376.0 9228.9 11129.9 11524.0 11533.9 11630.0 14486.7 14522.3 14525.4 14533.4 14558.2 6991.4 7990.2 8197.5 8258.1 8383.2 8081.7 8161.1 8202.3 8338.6 8342.3 4372.1 4387.1 4388.0 4396.1 4465.6 1738.0 1828.4 1885.6 1891.3 1895.7 723.3 789.9 790.9 905.4 977.5 20253.6 20954.2 21027.1 21065.6 21152.8 911.3 919.0 936.5 968.6 973.0 10441.4 10970.4 11000.9 11035.0 11043.9 600529.0 613333.9 615824.0 627080.0 629291.0 102175.0 105899.0 106093.5 106121.0 106925.0 946.9 950.0 951.5 972.1 978.5 17310.0 17509.7 17778.6 18156.3 18282.4 42921.4 50422.0 51451.0 53765.7 54679.2 69176.7 69462.0 72993.6 73218.6 74008.3 7555.2 7771.0 7813.6 7986.8 8067.6 9331.4 9711.0 11805.6 12300.6 12301.3 10618.9 10903.8 11096.0 11137.9 11298.1 9342.6 9987.9 10664.0 10752.9 11207.3 9057.0 11006.1 11082.0 11505.3 11515.6 6956.9 7231.1 7322.4 7478.9 8069.6 9390.2 10887.5 11116.3 11212.7 11316.8 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5513.58 Median:5287.94 stddev:3059.94 (57.9%) stddev-sans-first:3052.64 05:06:41 INFO - PROCESS | 1639 | Values: 1365.2 1442.4 1595.4 1757.1 1803.7 8199.8 8616.3 8617.4 8626.1 8627.7 7370.6 7497.7 7500.6 7736.2 7739.7 2101.6 2134.5 2275.6 2357.1 2359.6 2385.9 2386.3 2386.7 2389.0 2391.9 2302.4 2358.5 2361.1 2367.6 2370.1 2327.0 2334.0 2361.5 2361.8 2362.2 2001.4 2013.7 2358.1 2362.9 2382.1 8497.4 8552.4 8608.6 8631.2 8636.9 5141.9 5311.5 5312.9 5326.4 5330.7 3327.3 3501.1 3598.7 3655.9 3663.4 933.7 998.9 1044.5 1118.4 1122.6 11220.0 11306.3 11308.7 11317.9 11326.3 4492.2 4709.8 4787.5 4822.3 4828.9 9590.7 9615.5 9620.0 9627.1 9631.9 5283.7 5285.3 5290.6 5293.4 5294.5 4583.2 4847.9 4855.4 4865.6 4890.6 3500.0 3640.6 3647.0 3748.3 3913.7 1230.2 1236.6 1242.2 1243.5 1262.5 877.7 880.1 886.8 894.1 894.5 5322.9 5542.0 5544.4 5570.7 5660.4 860.6 886.8 922.0 931.2 944.0 3418.4 3759.3 3878.1 3896.2 3916.3 9909.6 10234.4 10410.3 10425.5 10531.4 10405.8 10465.7 10512.4 10522.5 10523.0 7982.6 8181.2 8204.9 8209.3 8214.5 5457.4 5555.5 5578.0 5661.0 5667.4 9563.8 9613.4 9711.8 9721.9 9726.9 9472.4 9484.4 9762.9 9882.2 10036.4 6689.3 6834.1 6840.5 6846.4 6848.3 7084.7 7356.3 7357.0 7461.2 7969.7 6573.0 7012.5 7233.0 7278.8 7287.6 7030.8 7123.0 7168.8 7172.9 7214.8 6698.1 6841.3 6844.9 6845.6 6858.6 4793.6 4824.0 4881.6 4935.2 5023.9 4755.8 4824.0 4860.5 4951.4 5046.4 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4419.84 Median:4892.45 stddev:1379.06 (28.2%) stddev-sans-first:1347.13 05:06:41 INFO - PROCESS | 1639 | Values: 260.7 302.3 302.6 313.2 317.0 5032.9 5154.4 5222.8 5256.2 5263.6 4638.4 5054.4 5135.9 5149.6 5193.7 3641.6 3774.8 4041.3 4050.4 4098.3 3233.8 4045.2 4055.5 4058.4 4069.7 3816.3 3978.9 4011.8 4027.9 4072.8 3831.4 4020.8 4023.8 4028.2 4034.6 3232.6 3327.8 3500.3 3517.5 3525.1 4152.4 4214.3 4221.9 5141.2 5150.4 4531.3 4565.5 4595.8 4618.3 4627.8 5512.5 5513.3 5533.5 5544.0 5577.8 3714.7 3871.5 3893.9 3896.1 3904.9 4674.6 4736.6 5132.8 5764.6 5773.1 3883.2 3926.2 3937.6 3947.8 3961.2 3395.2 3996.9 4007.9 4015.3 4024.3 3802.0 4200.9 4335.7 4609.3 4661.0 5570.6 5577.3 5577.8 5584.0 5595.3 1152.4 1192.8 1197.9 1239.7 1264.7 753.7 761.5 793.3 807.6 824.3 3958.3 4054.0 4634.3 4792.9 4815.1 5225.8 5229.3 5229.5 5264.6 5267.7 3984.3 4316.7 5234.4 5268.3 5317.8 2373.8 2545.9 2551.8 2805.0 2825.8 4568.9 5518.9 5597.5 5636.4 5639.1 4906.5 5091.5 5473.0 5856.3 5966.1 5466.9 5920.2 5927.3 5945.6 6121.5 5514.3 5721.7 5761.8 5791.2 5920.5 4470.5 4910.2 5182.6 5619.3 5668.3 5041.7 5499.5 5901.8 5979.6 6042.6 3095.7 3276.5 3806.9 3823.9 3860.9 5358.8 5742.9 5745.3 5754.6 5767.8 4619.4 4878.4 5461.2 5674.3 5675.6 4659.5 5448.9 5539.0 5587.0 5607.2 4379.5 5051.8 5108.8 5226.4 5535.3 4769.2 5064.0 5152.0 5351.1 5552.0 4873.9 5369.4 5405.6 5416.7 5498.2 05:06:41 INFO - PROCESS | 1639 | 05:06:41 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20569.18 Median:29069.52 stddev:30733.90 (105.7%) stddev-sans-first:30784.01 05:06:41 INFO - PROCESS | 1639 | Values: 727.7 735.2 757.8 774.2 792.4 15754.6 16401.3 16420.3 16436.9 16847.7 15039.6 15106.8 15118.3 15153.5 15205.6 648.3 669.5 813.7 841.0 844.3 804.7 825.0 832.5 840.4 840.9 873.0 876.8 877.6 880.2 880.8 680.7 871.7 872.9 874.0 874.5 518.4 619.9 638.0 643.5 644.6 13135.7 13430.3 14394.4 14435.9 14447.9 13238.3 13552.5 13563.5 13586.9 13618.9 12099.4 12107.9 12210.2 12245.5 12705.7 4433.8 4466.6 4471.0 4484.4 4603.8 81340.0 84606.0 84940.0 85289.6 85547.6 9632.8 10471.4 10557.0 10652.3 10737.1 19623.1 20354.9 20386.7 20396.0 20439.3 7215.0 7470.9 7577.5 7611.7 7702.6 4123.3 4206.2 4227.9 4252.3 4332.5 3230.1 3333.7 3334.7 3335.5 3341.4 618.7 627.6 642.9 643.6 647.3 541.3 586.4 605.5 614.3 615.7 8354.4 8401.7 8518.4 8638.1 8702.8 507.8 594.4 604.2 614.6 643.1 4188.7 4209.4 4234.2 4237.5 4307.4 112320.0 115873.0 117558.0 118878.0 120884.0 64559.1 66167.0 92077.5 94664.0 97771.0 89510.6 91104.0 91767.0 91997.0 92649.0 6805.7 8049.1 8123.1 8129.6 8477.7 44122.8 57597.7 58581.4 59503.0 59668.4 78904.0 82147.0 84089.0 84754.0 85701.6 10476.9 10734.0 10831.9 10852.6 11340.7 10390.3 10838.1 11391.4 11489.3 11687.5 9026.3 9111.5 12391.8 12586.6 12991.4 12222.6 12380.7 12815.0 12990.6 13179.7 10866.2 12152.2 12245.7 12492.7 12529.9 10066.3 10486.5 10626.1 10997.5 11016.4 7253.6 7447.6 8684.1 8829.0 9334.9 05:06:41 INFO - PROCESS | 1639 | -------- Summary: end -------- 05:06:41 INFO - PROCESS | 1639 | 05:06:42 INFO - TEST-INFO | 1639: exit 0 05:06:42 INFO - TEST-OK | dromaeo_css | took 1120760ms 05:06:42 INFO - TEST-START | kraken 05:06:42 INFO - operating with platform_type : mac_ 05:06:42 INFO - Initialising browser for kraken test... 05:06:42 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpjWVYvQ/profile http://localhost:49241/getInfo.html) 05:06:44 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 05:06:44 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 05:06:44 INFO - PROCESS | 1648 | colorDepth:24 05:06:44 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 05:06:44 INFO - PROCESS | 1648 | __metrics 05:06:44 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:06:44 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:45 INFO - TEST-INFO | 1648: exit 0 05:06:45 INFO - Browser initialized. 05:06:45 INFO - Running cycle 1/1 for kraken test... 05:06:45 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpjWVYvQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:57 INFO - PROCESS | 1649 | RSS: Main: 217350144 05:06:57 INFO - PROCESS | 1649 | 05:07:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:01 INFO - PROCESS | 1649 | RSS: Main: 266805248 05:07:01 INFO - PROCESS | 1649 | 05:07:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:07:04 INFO - PROCESS | 1649 | RSS: Main: 278495232 05:07:04 INFO - PROCESS | 1649 | 05:07:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:07:08 INFO - PROCESS | 1649 | RSS: Main: 375398400 05:07:08 INFO - PROCESS | 1649 | 05:07:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:07:10 INFO - PROCESS | 1649 | RSS: Main: 278286336 05:07:10 INFO - PROCESS | 1649 | 05:07:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:13 INFO - PROCESS | 1649 | RSS: Main: 279646208 05:07:13 INFO - PROCESS | 1649 | 05:07:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:17 INFO - PROCESS | 1649 | RSS: Main: 318738432 05:07:17 INFO - PROCESS | 1649 | 05:07:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:20 INFO - PROCESS | 1649 | RSS: Main: 320065536 05:07:20 INFO - PROCESS | 1649 | 05:07:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:07:24 INFO - PROCESS | 1649 | RSS: Main: 319152128 05:07:24 INFO - PROCESS | 1649 | 05:07:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:26 INFO - PROCESS | 1649 | RSS: Main: 296153088 05:07:26 INFO - PROCESS | 1649 | 05:07:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:28 INFO - PROCESS | 1649 | RSS: Main: 297177088 05:07:28 INFO - PROCESS | 1649 | 05:07:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:31 INFO - PROCESS | 1649 | RSS: Main: 304001024 05:07:31 INFO - PROCESS | 1649 | 05:07:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:35 INFO - PROCESS | 1649 | RSS: Main: 319086592 05:07:35 INFO - PROCESS | 1649 | 05:07:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:39 INFO - PROCESS | 1649 | RSS: Main: 312397824 05:07:39 INFO - PROCESS | 1649 | 05:07:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:07:41 INFO - PROCESS | 1649 | RSS: Main: 355971072 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | __start_tp_report 05:07:41 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 05:07:41 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 05:07:41 INFO - PROCESS | 1649 | |i|pagename|runs| 05:07:41 INFO - PROCESS | 1649 | |0;ai-astar;116;114;114;114;115;115;113;115;115;113 05:07:41 INFO - PROCESS | 1649 | |1;audio-beat-detection;170;140;140;147;117;114;113;111;143;117 05:07:41 INFO - PROCESS | 1649 | |2;audio-dft;184;184;183;183;182;184;182;181;184;183 05:07:41 INFO - PROCESS | 1649 | |3;audio-fft;78;58;59;92;61;59;59;60;82;58 05:07:41 INFO - PROCESS | 1649 | |4;audio-oscillator;87;70;93;71;67;69;89;90;99;125 05:07:41 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;93;88;90;90;90;86;92;87;91;86 05:07:41 INFO - PROCESS | 1649 | |6;imaging-darkroom;112;148;148;95;95;95;95;96;95;95 05:07:41 INFO - PROCESS | 1649 | |7;imaging-desaturate;85;118;78;81;84;81;85;83;80;81 05:07:41 INFO - PROCESS | 1649 | |8;json-parse-financial;57;55;55;56;59;58;58;56;57;57 05:07:41 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;76;79;72;71;69;94;102;102;86 05:07:41 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;84;85;82;88;83;81;83;87;91;85 05:07:41 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;145;142;143;143;139;144;147;148;183;175 05:07:41 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;169;167;168;192;216;196;166;219;214;168 05:07:41 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;67;66;66;66;65;68;65;66;66 05:07:41 INFO - PROCESS | 1649 | __end_tp_report 05:07:41 INFO - PROCESS | 1649 | __start_cc_report 05:07:41 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2031 05:07:41 INFO - PROCESS | 1649 | __end_cc_report 05:07:41 INFO - PROCESS | 1649 | __startTimestamp1479474461013__endTimestamp 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | ------- Summary: start ------- 05:07:41 INFO - PROCESS | 1649 | Number of tests: 14 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:0.97 (0.8%) stddev-sans-first:0.83 05:07:41 INFO - PROCESS | 1649 | Values: 116.0 114.0 114.0 114.0 115.0 115.0 113.0 115.0 115.0 113.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:140.00 stddev:19.69 (14.1%) stddev-sans-first:15.06 05:07:41 INFO - PROCESS | 1649 | Values: 170.0 140.0 140.0 147.0 117.0 114.0 113.0 111.0 143.0 117.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.05 (0.6%) stddev-sans-first:1.05 05:07:41 INFO - PROCESS | 1649 | Values: 184.0 184.0 183.0 183.0 182.0 184.0 182.0 181.0 184.0 183.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.51 (20.7%) stddev-sans-first:12.57 05:07:41 INFO - PROCESS | 1649 | Values: 78.0 58.0 59.0 92.0 61.0 59.0 59.0 60.0 82.0 58.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:88.00 stddev:17.94 (20.4%) stddev-sans-first:19.02 05:07:41 INFO - PROCESS | 1649 | Values: 87.0 70.0 93.0 71.0 67.0 69.0 89.0 90.0 99.0 125.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:2.45 (2.7%) stddev-sans-first:2.20 05:07:41 INFO - PROCESS | 1649 | Values: 93.0 88.0 90.0 90.0 90.0 86.0 92.0 87.0 91.0 86.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:95.00 stddev:22.04 (23.2%) stddev-sans-first:23.31 05:07:41 INFO - PROCESS | 1649 | Values: 112.0 148.0 148.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:82.00 stddev:11.61 (14.2%) stddev-sans-first:12.31 05:07:41 INFO - PROCESS | 1649 | Values: 85.0 118.0 78.0 81.0 84.0 81.0 85.0 83.0 80.0 81.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:1.32 (2.3%) stddev-sans-first:1.39 05:07:41 INFO - PROCESS | 1649 | Values: 57.0 55.0 55.0 56.0 59.0 58.0 58.0 56.0 57.0 57.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:74.00 stddev:13.67 (18.5%) stddev-sans-first:13.12 05:07:41 INFO - PROCESS | 1649 | Values: 65.0 76.0 79.0 72.0 71.0 69.0 94.0 102.0 102.0 86.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:3.03 (3.6%) stddev-sans-first:3.20 05:07:41 INFO - PROCESS | 1649 | Values: 84.0 85.0 82.0 88.0 83.0 81.0 83.0 87.0 91.0 85.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:146.00 stddev:15.14 (10.4%) stddev-sans-first:15.91 05:07:41 INFO - PROCESS | 1649 | Values: 145.0 142.0 143.0 143.0 139.0 144.0 147.0 148.0 183.0 175.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:194.00 stddev:22.57 (11.6%) stddev-sans-first:22.92 05:07:41 INFO - PROCESS | 1649 | Values: 169.0 167.0 168.0 192.0 216.0 196.0 166.0 219.0 214.0 168.0 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 05:07:41 INFO - PROCESS | 1649 | Values: 67.0 67.0 66.0 66.0 66.0 65.0 68.0 65.0 66.0 66.0 05:07:41 INFO - PROCESS | 1649 | -------- Summary: end -------- 05:07:41 INFO - PROCESS | 1649 | 05:07:41 INFO - TEST-INFO | 1649: exit 0 05:07:41 INFO - TEST-OK | kraken | took 59507ms 05:07:41 INFO - SUITE-END | took 1180s 05:07:42 INFO - Completed test suite (00:19:40) 05:07:42 INFO - javascript benchmark 05:07:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.5501057357255, 3333.1833700743305, 3339.7829141105826, 3370.325934813035, 3395.507651390547, 4983.676061056029, 5260.447652996438, 5577.665340079598, 5616.185653080312, 5690.260266165403, 5639.182318563806, 5678.091505359143, 5680.829575112753, 5709.143628455734, 5726.427357264271, 5155.7679904404295, 5164.638475306711, 5184.662996905189, 5219.329814459375, 5235.083860324447, 5125.256837758523, 5130.256112863633, 5151.845444636667, 5304.071787437202, 5332.440093790155, 4592.862214133581, 4909.116359055369, 5230.686158830464, 5236.057509648262, 5239.685618862881, 5233.712145831978, 5239.16173412256, 5240.371155461331, 5247.0, 5261.026710058652, 5136.101182293121, 5167.379914410295, 5192.532672059495, 5196.0647083524955, 5204.791808327663, 5702.572307076985, 5710.172025056385, 5717.056685646863, 5717.542596592262, 5721.0, 6118.040554665137, 6131.499004853489, 6134.234418595382, 6136.6011209271255, 6140.381550880938, 6211.503079753609, 6229.844253893643, 6243.406876346739, 6243.56295059346, 6308.022256550242, 4843.854684359475, 5356.892862142735, 5760.135979603051, 5799.971000145015, 5827.067669172912, 6251.6561589113035, 6290.182276304143, 6302.810915672537, 6317.684115794192, 6341.397567231105, 6047.334793172748, 6059.939400606026, 6095.878082438415, 6101.816945491553, 6108.076676490625, 6258.499320054385, 6264.498840092781, 6286.937130628727, 6310.1796766420075, 6310.5898116622275, 6236.968815155895, 6239.656818874914, 6258.186435763413, 6265.8120256392385, 6267.905981410283, 6057.061155520902, 6124.960759790135, 6129.540284478593, 6140.078988151813, 6144.494598823317, 4950.2822090796635, 4968.30443737877, 4975.477574854588, 4996.400431948189, 4998.525140111684, 3916.236333914916, 4430.291153415438, 4431.578999994996, 4461.174682683714, 4473.977630111829, 5642.351129620146, 5728.1694154348215, 5872.618279811799, 5882.676452795137, 5919.378465261172, 6187.504999600022, 6222.222222222223, 6271.560990730605, 6295.087212354183, 6329.430351268408, 5828.329742079715, 5829.038208695518, 5838.9124163137585, 5848.61983971049, 5854.414558544111, 4596.977015114903, 5358.678479291255, 5398.865028374322, 5424.647397919122, 5427.48438898304, 5882.970585147047, 6113.476306051939, 6226.346233645583, 6229.688515574113, 6230.0, 6309.716062777278, 6320.273168585672, 6337.4613157882095, 6342.080398342307, 6345.428911397812, 6324.905126423016, 6336.017917222838, 6336.144620476177, 6348.936510634834, 6355.968220158869, 6108.4807791338235, 6120.447133218882, 6140.140380346845, 6174.969125154345, 6232.657203853832, 6126.639818174547, 6233.625982441068, 6234.812955611339, 6283.183186185766, 6297.4647154992335, 6102.633841969496, 6117.082437634391, 6119.0, 6132.08018797184, 6144.0, 5869.501092407193, 6013.488853447506, 6121.418465245794, 6138.07928810682, 6144.201253836973, 5819.098039803837, 5871.735771890189, 5898.616589921813, 5942.168096466585, 5942.435468630473, 6144.832481828438, 6151.5386346025125, 6164.229471316085, 6164.537659675632, 6173.59871608344, 5797.8550536236935, 5827.59206855516, 5844.327902291291, 5846.152307915243, 5854.268216472941], "value": 5675.623855731878, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.3637811630894, 501.593031546512, 506.74327667384887, 515.4948150812204, 552.6711606594077, 28624.141275761733, 38728.612713872855, 39888.202235955265, 40036.39945400821, 41785.7910710446, 40644.59355406445, 40789.0, 41115.58884411155, 41122.38316425256, 41140.58859411405, 8467.364947628921, 9308.301877359208, 9499.382540134904, 9504.477253751042, 9509.239260859114, 8466.872996905051, 11138.888611113907, 11419.371934543597, 11449.484773185184, 11635.24370915892, 11195.720106997349, 11323.886761132408, 11341.206115571913, 11345.546178152865, 11353.886461135407, 11295.096392288597, 11368.772624547546, 11381.715457113556, 11384.715382115513, 11403.3728144952, 11086.667399978014, 11306.717332066683, 11307.0, 11309.321440713584, 11319.943400282946, 38932.0, 39157.80421097905, 39266.0, 39280.0, 39311.47917989439, 3167.1448708848484, 3231.353729254135, 3411.266577685785, 3519.454484554898, 3576.409892367782, 29964.8501757492, 30036.948706795156, 30291.545626815616, 30643.387132257452, 31060.068197954097, 3151.243701511644, 3165.683431656839, 3207.695268949446, 3223.903282901517, 3252.08941496379, 423359.0, 426360.0, 427943.0, 431055.0, 431571.0, 32732.0, 44552.554474455486, 53012.20481692778, 54061.1890821638, 54280.45719542754, 33589.83205083959, 34600.65399346025, 34642.82678586591, 34666.9599912003, 34725.30549389048, 14048.648783780487, 14180.432782688576, 14191.432342706175, 14203.502877399447, 14268.57194284173, 10871.565137394573, 11053.778924421467, 11933.224340417844, 12036.81944770829, 12053.0, 7412.851008093754, 7514.736984205527, 7700.537967721954, 7701.191374905667, 7701.383889288843, 2490.7384724603658, 3164.1298642873394, 3166.3983843070205, 3186.3786561620263, 3199.696028877253, 884.1909652667865, 920.6593560382568, 945.976350591227, 1048.9003544663244, 1105.40308233553, 29251.853740731585, 29283.707162928524, 29564.704352956625, 29936.700632993827, 29998.95003674861, 1000.5997600959674, 1046.3564907581783, 1066.7973085113804, 1081.4106312059946, 1102.818035024228, 12436.37818109091, 14904.329305181293, 15181.316840741973, 15278.541643750705, 15706.0, 673592.0, 678905.0, 679383.0, 680951.0, 681087.594562024, 56838.43161568414, 57390.139147912814, 57525.0, 57597.42402576005, 57838.0, 1205.3671822293365, 1233.173773571691, 1317.6771690935782, 1342.5234041915176, 1351.8175046368615, 28922.0, 30181.849090754407, 30493.54259686062, 30654.55834410914, 30910.845445772626, 48584.514154858, 49122.0, 49178.262326064425, 49246.75376623094, 49317.50682493129, 528923.0, 530633.0, 531169.0, 532988.0, 534508.0, 22738.31785046451, 27592.862035689694, 27603.0, 27640.032598858943, 27954.440911181257, 44070.118597627225, 44097.0, 44397.0, 44656.0, 45253.09493810171, 33209.33581328311, 36130.63869361273, 37823.0, 38090.80954595209, 38674.0, 37173.0, 38057.858264252114, 38066.42900356552, 40020.599794002854, 40177.39733903935, 37537.37238393286, 37617.4357384634, 37767.43348849715, 37786.43320350142, 38464.6153538461, 32924.50613240755, 33591.32817343689, 34494.82752586321, 34562.0, 34709.0, 20358.898205508878, 25265.62101568515, 25462.618060728735, 25477.0, 26051.218463446126], "value": 18453.56022417558, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7397126126667, 368.22672387985233, 371.64693541135927, 384.14336030651657, 401.1435585025969, 14670.0, 14823.925880370596, 15641.687166256668, 19634.312799052037, 20327.796722032774, 18863.905680471613, 19061.52346191346, 19824.107915143817, 19854.20583176671, 19918.402447926586, 5551.361593416758, 5844.824655260349, 5858.472737453629, 5860.648361098327, 5862.032764593858, 5346.545543628797, 5667.319921609392, 5706.572007099463, 5707.885842283173, 5710.486056254937, 5609.1305847593685, 5692.459216374438, 5693.829185124432, 5698.43015698431, 5701.77192912283, 4398.3842262083335, 5680.886382272352, 5737.39757325479, 5744.856378590527, 5748.396418376052, 4789.161896668104, 5025.773840177201, 5032.547070763612, 5039.52124548171, 5041.9747901260625, 19857.503562411057, 24090.879545602158, 24416.389590260387, 24647.630285545732, 24724.629130563055, 2143.3141394753684, 2246.1240116354634, 2422.5760491913843, 2443.8900249488815, 2925.0, 14125.576232713034, 14300.070495417765, 14752.262386880613, 15213.847861521464, 15283.0, 1778.0665150795935, 1789.8031216566167, 1833.1475863723433, 1841.3647291684347, 1897.9240830366768, 120090.7990920097, 124216.0, 128465.99999999907, 130883.0, 132376.0, 9228.907710922806, 11129.944350278196, 11524.0, 11533.942330288295, 11630.0, 14486.710265794836, 14522.273886305642, 14525.418983240552, 14533.418663253562, 14558.199299038655, 6991.440684745208, 7990.20097990208, 8197.50814951093, 8258.091609922903, 8383.203595658402, 8081.676732930617, 8161.143079976636, 8202.343812494986, 8338.583070846555, 8342.332613390914, 4372.103718737651, 4387.100644367897, 4387.978060109743, 4396.07682386696, 4465.620422264079, 1737.991310043467, 1828.4240464253717, 1885.6040231551253, 1891.297831951255, 1895.6587814193301, 723.2731105239307, 789.8657228271207, 790.8966991047456, 905.4160066757037, 977.5014742481386, 20253.594928101356, 20954.161833526792, 21027.05378257982, 21065.578688426453, 21152.78847211508, 911.2755359489222, 918.9908100919039, 936.4849332866896, 968.6464440479315, 973.0221127766691, 10441.425721585385, 10970.396628185526, 11000.944995274973, 11035.0, 11043.944780276048, 600529.0, 613333.9333303305, 615824.0, 627080.0, 629291.0, 102175.0, 105899.0, 106093.46953265338, 106121.0, 106925.0, 946.8911075226437, 950.0499500499501, 951.481442613791, 972.0522490571777, 978.5351957819978, 17310.0, 17509.737353939447, 17778.644427111645, 18156.27374904989, 18282.360117395827, 42921.35617965795, 50422.0, 51451.0, 53765.73117134389, 54679.17981230205, 69176.6541167291, 69462.0, 72993.63503182662, 73218.63390683013, 74008.25991740014, 7555.244475552401, 7770.950921625508, 7813.570253636105, 7986.84026319482, 8067.556284404414, 9331.440113593206, 9711.0, 11805.645830625095, 12300.569480068154, 12301.32342721159, 10618.946905265424, 10903.836442453528, 11096.0, 11137.944310278397, 11298.09615230796, 9342.579583918605, 9987.850182247417, 10664.040236378763, 10752.892471075502, 11207.327560346404, 9056.954715226382, 11006.119510438986, 11082.0, 11505.309681419141, 11515.596954106566, 6956.895646565406, 7231.096112985877, 7322.3775979042375, 7478.887816682645, 8069.636866340911, 9390.248780097712, 10887.455627218766, 11116.27744196657, 11212.66362009141, 11316.773664526829], "value": 9273.680378482302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.1536047650459, 1442.3725679329493, 1595.35388167792, 1757.1302205408308, 1803.7294405839123, 8199.75400737978, 8616.31069514439, 8617.439866408678, 8626.094260102693, 8627.698030568932, 7370.59461729606, 7497.73757918473, 7500.624968751568, 7736.226377362261, 7739.690412383512, 2101.590189912964, 2134.4770531219797, 2275.590393729128, 2357.1043003658588, 2359.6460530920413, 2385.9045638174453, 2386.260259319608, 2386.6658667786523, 2389.032441861049, 2391.9162829300976, 2302.4013756423283, 2358.50471401006, 2361.090979972714, 2367.573836709392, 2370.0519792083132, 2327.0, 2334.0, 2361.504084142332, 2361.8464799787957, 2362.1850461590725, 2001.35956493922, 2013.68103891951, 2358.092134528219, 2362.9409264768346, 2382.1305223593436, 8497.362697797627, 8552.401331906769, 8608.612612432456, 8631.180037896389, 8636.870446943238, 5141.948580514184, 5311.49540793624, 5312.893742125136, 5326.36083669962, 5330.733463326818, 3327.2513684421056, 3501.124718820295, 3598.6941110005682, 3655.853765849363, 3663.358912190356, 933.6965486217007, 998.8651532043153, 1044.4986406524836, 1118.401655114518, 1122.5565901468956, 11220.0, 11306.321620702784, 11308.660740177807, 11317.886821131766, 11326.263792853439, 4492.2363198255725, 4709.8351557696005, 4787.54518320759, 4822.348982887335, 4828.879278018007, 9590.712278631652, 9615.471149086867, 9620.0, 9627.085426884434, 9631.903680963242, 5283.709395983258, 5285.286486324374, 5290.603204759658, 5293.417724050351, 5294.49702278283, 4583.243764778841, 4847.903041939141, 4855.393075865517, 4865.635077369141, 4890.559849613552, 3499.9825000874835, 3640.5995340512522, 3647.0306034740943, 3748.269087527906, 3913.726039177287, 1230.194222784078, 1236.5981056156786, 1242.1553343726353, 1243.5461056714414, 1262.5202423079172, 877.7322916510398, 880.0847118996301, 886.782738229138, 894.088030209183, 894.5303715549388, 5322.866928326745, 5542.0, 5544.445555444423, 5570.6657600544095, 5660.433956604389, 860.5697151424288, 886.782738229138, 922.0456827183832, 931.2456909903, 943.9858402123974, 3418.447107868711, 3759.285735710206, 3878.108035151931, 3896.2207558488417, 3916.3342231820657, 9909.554070066864, 10234.437105959094, 10410.323328983592, 10425.478726063666, 10531.368117912949, 10405.791884162274, 10465.738356541147, 10512.42181680015, 10522.473876306154, 10523.0, 7982.600869956479, 8181.18188181189, 8204.933358663335, 8209.302209312274, 8214.4660597061, 5457.372402173801, 5555.527780138734, 5577.972110139423, 5661.0, 5667.40492248308, 9563.760905977406, 9613.423194608346, 9711.757206069622, 9721.902780972383, 9726.902730972599, 9472.384295021076, 9484.383515071499, 9762.902370976199, 9882.160016398686, 10036.447995360326, 6689.264180939996, 6834.111565496453, 6840.452763778985, 6846.418054465227, 6848.315168483112, 7084.716611335489, 7356.337929586363, 7357.043584334002, 7461.216572260052, 7969.72105976288, 6572.967135164294, 7012.509124361246, 7233.0, 7278.781636550912, 7287.599182045038, 7030.789076327718, 7122.964385178248, 7168.784936451915, 7172.928270717226, 7214.783556493314, 6698.095757072928, 6841.281665425058, 6844.93155068443, 6845.623490708059, 6858.554193977376, 4793.640476964172, 4823.95176048235, 4881.585065269492, 4935.235038569028, 5023.874403139951, 4755.809767609257, 4824.0, 4860.465348811557, 4951.356323678043, 5046.369203849519], "value": 4451.898132059311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.67676081658743, 302.2564491351276, 302.571860816944, 313.19821257580594, 317.04885343968095, 5032.949670503293, 5154.4072431670365, 5222.764975576099, 5256.211568264762, 5263.578913686905, 4638.3970083889135, 5054.393472783262, 5135.948640513594, 5149.639525233235, 5193.714345710984, 3641.563012438498, 3774.811259437031, 4041.252368311857, 4050.432939388488, 4098.323776576876, 3233.8059716416974, 4045.2314060328445, 4055.472788537486, 4058.3506638937774, 4069.674426045924, 3816.313063648542, 3978.860739874105, 4011.7793521356316, 4027.932597861569, 4072.8370865165357, 3831.386978083521, 4020.8190631421658, 4023.76680942077, 4028.194361127786, 4034.5763694812063, 3232.6282477514915, 3327.8336083195745, 3500.3349363621087, 3517.5427194464555, 3525.1363415962996, 4152.418661387408, 4214.346776249687, 4221.936670949938, 5141.203113517411, 5150.381954165524, 4531.3429552715, 4565.497795242521, 4595.805090676415, 4618.307253911907, 4627.8148874045, 5512.476314750093, 5513.338399392099, 5533.501984821345, 5544.0, 5577.776888924438, 3714.739968202227, 3871.4967054283043, 3893.8831835044994, 3896.1428485733095, 3904.863329783444, 4674.555917187861, 4736.550027747358, 5132.820351287687, 5764.561287808406, 5773.076307790733, 3883.194314643648, 3926.234384295035, 3937.5668676445566, 3947.842086316515, 3961.2473630010204, 3395.2021275000357, 3996.9200615987515, 4007.91984160321, 4015.3374693175297, 4024.3158663027357, 3801.9734671639058, 4200.85297014603, 4335.653147748172, 4609.285560738091, 4660.997885454643, 5570.554355651539, 5577.274954256002, 5577.776888924479, 5584.0, 5595.328560572676, 1152.4468255237366, 1192.8151136573845, 1197.9221350612183, 1239.7458521003352, 1264.6522206393315, 753.650964773061, 761.5012167030604, 793.2701914238914, 807.6471909551477, 824.2993455562844, 3958.3072962231495, 4053.966238609136, 4634.304854271819, 4792.880177995508, 4815.109204797123, 5225.817096401608, 5229.267902493654, 5229.50319719626, 5264.578833693296, 5267.683938963674, 3984.2828290907337, 4316.6546676265825, 5234.398044224963, 5268.262443257947, 5317.813876514303, 2373.774491423312, 2545.879812882326, 2551.813406765863, 2804.9859750701116, 2825.7598104160966, 4568.949141697428, 5518.862028449241, 5597.460698307917, 5636.436356364331, 5639.080838817064, 4906.460289368167, 5091.465396133352, 5473.0, 5856.297244330707, 5966.134910437962, 5466.945330546723, 5920.2007728956905, 5927.288725352899, 5945.554083443845, 6121.540884433596, 5514.310711161105, 5721.654121810038, 5761.798337058183, 5791.220214297076, 5920.526357891272, 4470.485894122217, 4910.207774162476, 5182.590095605561, 5619.269494965628, 5668.319801623831, 5041.722705251246, 5499.532539734168, 5901.793437229677, 5979.611325263844, 6042.607230530001, 3095.7059079387423, 3276.45938420158, 3806.885793426202, 3823.866164684223, 3860.9227815442973, 5358.758855851574, 5742.856428589319, 5745.3105627324985, 5754.597178197486, 5767.826965191051, 4619.399478067964, 4878.438979517401, 5461.235427040297, 5674.319081710221, 5675.631083979528, 4659.4623774155125, 5448.945510544844, 5539.0, 5587.0, 5607.214989901336, 4379.518252992232, 5051.772670229775, 5108.79564817418, 5226.398964119175, 5535.335759708861, 4769.2369220925875, 5063.974680126576, 5151.960343397413, 5351.143816989263, 5551.9722401387735, 4873.905919028033, 5369.4093649697725, 5405.567554595545, 5416.729163541886, 5498.202760599771], "value": 3972.0708977824142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.6870945493439, 735.2059775442523, 757.7613051888654, 774.206438400639, 792.4254915186486, 15754.606134846634, 16401.343946242152, 16420.343186272563, 16436.91781541092, 16847.66304673909, 15039.624009399771, 15106.773398399006, 15118.319675614597, 15153.54539363818, 15205.619859503518, 648.2739331948225, 669.4945316286221, 813.7070654564352, 840.9873851892196, 844.2612713875359, 804.74650485097, 824.9752507424787, 832.4838600067966, 840.3613253927028, 840.8738689196639, 873.0134947509306, 876.842168409686, 877.5875338590884, 880.1594477274219, 880.8150288439435, 680.7277089164324, 871.6687658689657, 872.8603423452249, 873.9868901966476, 874.4796845876693, 518.380535260364, 619.9132121502993, 637.9919726831595, 643.4980715042275, 644.555256872761, 13135.686431356788, 13430.328483575782, 14394.424223031067, 14435.85564144366, 14447.85552144486, 13238.27189504577, 13552.457901683922, 13563.525276615272, 13586.86413135866, 13618.863811361858, 12099.395030248541, 12107.878921210764, 12210.206336588093, 12245.510179592806, 12705.682357941125, 4433.8448154314765, 4466.57567531084, 4471.0, 4484.439445069366, 4603.8158473661015, 81340.0, 84606.0, 84940.0, 85289.57355213247, 85547.57226213891, 9632.844058712999, 10471.42219798747, 10557.049865512141, 10652.30760000613, 10737.141028717684, 19623.11695973656, 20354.898225509074, 20386.69419958702, 20396.0, 20439.284625038053, 7215.0, 7470.85058298831, 7577.469577129548, 7611.733589324347, 7702.576358300347, 4123.319652257344, 4206.242876282237, 4227.915441691149, 4252.29837076885, 4332.458442694663, 3230.0632816483426, 3333.7333013358875, 3334.699877011081, 3335.533025376449, 3341.4486609709124, 618.7432215630463, 627.6391075131818, 642.9003504456816, 643.5591619740493, 647.252423450915, 541.3260490689039, 586.4018700925118, 605.461139585769, 614.3487902823022, 615.7044618583016, 8354.373421993374, 8401.747947561584, 8518.403711740242, 8638.09300023501, 8702.825943481095, 507.7689651208691, 594.3699678340972, 604.2356419129804, 614.6496496996758, 643.0611307491022, 4188.748675079505, 4209.368594710757, 4234.237837189274, 4237.491501019897, 4307.375430562551, 112320.0, 115873.0, 117558.0, 118878.0, 120884.0, 64559.12645241206, 66167.0, 92077.53961230151, 94664.0, 97771.0, 89510.55244723865, 91103.99999999868, 91767.0, 91997.0, 92649.0, 6805.740937926499, 8049.114597394165, 8123.065847427385, 8129.552874591717, 8477.74566762998, 44122.77938610286, 57597.712011439675, 58581.4141858576, 59503.0, 59668.43647198718, 78904.0, 82147.0, 84089.0, 84754.0, 85701.57149214463, 10476.895231047896, 10734.0, 10831.945840270748, 10852.565897364017, 11340.659780206606, 10390.272680912263, 10838.078763304891, 11391.430428478378, 11489.310641361544, 11687.532498699957, 9026.323025772961, 9111.498867562348, 12391.814122787984, 12586.622401327975, 12991.41538630745, 12222.633321000385, 12380.690482738004, 12815.0, 12990.610281691565, 13179.67050823737, 10866.239363244813, 12152.21010634306, 12245.693857653274, 12492.687682807638, 12529.89715379093, 10066.295359324777, 10486.475676216007, 10626.149908007495, 10997.505112270128, 11016.449177540931, 7253.601051942193, 7447.5531468112085, 8684.131586841266, 8828.95585522094, 9334.859977100485], "value": 7128.061413821467, "unit": "score"}], "name": "dromaeo_css", "value": 7046.72636046874, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 115.0, 115.0, 113.0, 115.0, 115.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 140.0, 140.0, 147.0, 117.0, 114.0, 113.0, 111.0, 143.0, 117.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 183.0, 182.0, 184.0, 182.0, 181.0, 184.0, 183.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 59.0, 92.0, 61.0, 59.0, 59.0, 60.0, 82.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 93.0, 71.0, 67.0, 69.0, 89.0, 90.0, 99.0, 125.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 90.0, 90.0, 90.0, 86.0, 92.0, 87.0, 91.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 148.0, 148.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 118.0, 78.0, 81.0, 84.0, 81.0, 85.0, 83.0, 80.0, 81.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 56.0, 59.0, 58.0, 58.0, 56.0, 57.0, 57.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 79.0, 72.0, 71.0, 69.0, 94.0, 102.0, 102.0, 86.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 88.0, 83.0, 81.0, 83.0, 87.0, 91.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 143.0, 143.0, 139.0, 144.0, 147.0, 148.0, 183.0, 175.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 168.0, 192.0, 216.0, 196.0, 166.0, 219.0, 214.0, 168.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 65.0, 68.0, 65.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1491.4, "alertThreshold": 2.0}]} 05:07:42 INFO - Return code: 0 05:07:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:07:42 ERROR - # TBPL SUCCESS # 05:07:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:42 INFO - 2016-11-18 05:07:42.361 screenresolution[1653:9857] starting screenresolution argv=screenresolution get 05:07:42 INFO - 2016-11-18 05:07:42.364 screenresolution[1653:9857] Display 0: 1600x1200x32@60 05:07:42 INFO - 2016-11-18 05:07:42.370 screenresolution[1654:9861] starting screenresolution argv=screenresolution list 05:07:42 INFO - Available Modes on Display 0 05:07:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:42 INFO - Intel Iris: 05:07:42 INFO - Chipset Model: Intel Iris 05:07:42 INFO - Type: GPU 05:07:42 INFO - Bus: Built-In 05:07:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:42 INFO - Vendor: Intel (0x8086) 05:07:42 INFO - Device ID: 0x0a2e 05:07:42 INFO - Revision ID: 0x0009 05:07:42 INFO - Displays: 05:07:42 INFO - Display: 05:07:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:42 INFO - Display Serial Number: 1600x1200 60 05:07:42 INFO - Main Display: Yes 05:07:42 INFO - Mirror: Off 05:07:42 INFO - Online: Yes 05:07:42 INFO - Rotation: Supported 05:07:42 INFO - Return code: 0 05:07:42 INFO - Running post-action listener: _resource_record_post_action 05:07:42 INFO - [mozharness: 2016-11-18 13:07:42.606516Z] Finished run-tests step (success) 05:07:42 INFO - Running post-run listener: _resource_record_post_run 05:07:42 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 37504000; Write bytes: 653870080; Read time: 233; Write time: 1869 05:07:42 INFO - TinderboxPrint: CPU usage
53.3% 05:07:42 INFO - TinderboxPrint: I/O read bytes / time
37,504,000 / 233 05:07:42 INFO - TinderboxPrint: I/O write bytes / time
653,870,080 / 1,869 05:07:42 INFO - TinderboxPrint: CPU idle
2,269.8 (46.7%) 05:07:42 INFO - TinderboxPrint: CPU system
969.4 (20.0%) 05:07:42 INFO - TinderboxPrint: CPU user
1,618.4 (33.3%) 05:07:42 INFO - TinderboxPrint: Swap in / out
390,615,040 / 0 05:07:42 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 354193920; Write bytes: 355756032; Read time: 28490; Write time: 769 05:07:42 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34370560; Write bytes: 295089664; Read time: 211; Write time: 1084 05:07:43 INFO - Running post-run listener: _upload_blobber_files 05:07:43 INFO - Blob upload gear active. 05:07:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:43 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 05:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:45 INFO - (blobuploader) - INFO - Done attempting. 05:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:46 INFO - (blobuploader) - INFO - Done attempting. 05:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:07:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:49 INFO - (blobuploader) - INFO - Done attempting. 05:07:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:49 INFO - Return code: 0 05:07:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d0baa3afe1e073edc25f5f690932b7cad9a9f4ca5770d1a3cec8047b399311f2a909cabc1c87459d39c52c3dedac57d151126927b15725b084967a083329ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752a37f665b98466d25598b7cf84498e11f8a0ca1ad75eb01d05b64b48de1eca210377a2d2ec738e7e9a3013808be6c97199fe2ae454156eb5ea6f01b97b5697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/172f72a33143ac1a7eead7a118209387e1e777d3c7b11f23f9a67c325f53573327640ebf1ecf2e302a9a21e2264144f3d372e70ce00d1fe0f1cb92f6504584cd"} 05:07:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:49 INFO - Contents: 05:07:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d0baa3afe1e073edc25f5f690932b7cad9a9f4ca5770d1a3cec8047b399311f2a909cabc1c87459d39c52c3dedac57d151126927b15725b084967a083329ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752a37f665b98466d25598b7cf84498e11f8a0ca1ad75eb01d05b64b48de1eca210377a2d2ec738e7e9a3013808be6c97199fe2ae454156eb5ea6f01b97b5697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/172f72a33143ac1a7eead7a118209387e1e777d3c7b11f23f9a67c325f53573327640ebf1ecf2e302a9a21e2264144f3d372e70ce00d1fe0f1cb92f6504584cd"} 05:07:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:49 INFO - Copying logs to upload dir... 05:07:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.188619 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-18 05:07:50.955607) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 05:07:50.959200) ========= 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.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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/Mozilla-Aurora/sha512/9d0baa3afe1e073edc25f5f690932b7cad9a9f4ca5770d1a3cec8047b399311f2a909cabc1c87459d39c52c3dedac57d151126927b15725b084967a083329ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752a37f665b98466d25598b7cf84498e11f8a0ca1ad75eb01d05b64b48de1eca210377a2d2ec738e7e9a3013808be6c97199fe2ae454156eb5ea6f01b97b5697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/172f72a33143ac1a7eead7a118209387e1e777d3c7b11f23f9a67c325f53573327640ebf1ecf2e302a9a21e2264144f3d372e70ce00d1fe0f1cb92f6504584cd"} build_url:https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/Z-JQHDPrSsO5p12tt-NF_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d0baa3afe1e073edc25f5f690932b7cad9a9f4ca5770d1a3cec8047b399311f2a909cabc1c87459d39c52c3dedac57d151126927b15725b084967a083329ad9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752a37f665b98466d25598b7cf84498e11f8a0ca1ad75eb01d05b64b48de1eca210377a2d2ec738e7e9a3013808be6c97199fe2ae454156eb5ea6f01b97b5697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/172f72a33143ac1a7eead7a118209387e1e777d3c7b11f23f9a67c325f53573327640ebf1ecf2e302a9a21e2264144f3d372e70ce00d1fe0f1cb92f6504584cd"}' ========= master_lag: 0.85 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 05:07:51.816379) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:07:51.816702) ========= 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.QWiylcnWJA/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.UeGgqmvw4n/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005201 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:07:51.842090) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 05:07:51.842377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 05:07:51.842660) ========= ========= Total master_lag: 1.90 =========