builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0284 starttime: 1479568148.65 results: success (0) buildid: 20161119060822 builduid: dd4abdc43ab744439171eb6d5aa52e67 revision: e59ed8b3ba13fd33435eea75da37ea6029c9af40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:08.651022) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:08.651522) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:08.651858) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:08.678459) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:08.678787) ========= 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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-19 07:09:08-- 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% 73.0M=0s 2016-11-19 07:09:09 (73.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.485985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:09.187524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:09.187862) ========= 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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.024683 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:09.236441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 07:09:09.236811) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e59ed8b3ba13fd33435eea75da37ea6029c9af40 --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 e59ed8b3ba13fd33435eea75da37ea6029c9af40 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-19 07:09:09,339 truncating revision to first 12 chars 2016-11-19 07:09:09,339 Setting DEBUG logging. 2016-11-19 07:09:09,339 attempt 1/10 2016-11-19 07:09:09,339 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e59ed8b3ba13?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-19 07:09:10,112 unpacking tar archive at: mozilla-aurora-e59ed8b3ba13/testing/mozharness/ program finished with exit code 0 elapsedTime=1.057443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 07:09:10.318724) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:10.319073) ========= script_repo_revision: e59ed8b3ba13fd33435eea75da37ea6029c9af40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:10.319530) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:10.319854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 07:09:10.337866) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-19 07:09:10.338172) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:10 INFO - MultiFileLogger online at 20161119 07:09:10 in /builds/slave/test 07:09:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:09:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:09:10 INFO - {'append_to_log': False, 07:09:10 INFO - 'base_work_dir': '/builds/slave/test', 07:09:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:09:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:09:10 INFO - 'branch': 'Mozilla-Aurora', 07:09:10 INFO - 'buildbot_json_path': 'buildprops.json', 07:09:10 INFO - 'config_files': ('talos/mac_config.py',), 07:09:10 INFO - 'default_actions': ('clobber', 07:09:10 INFO - 'read-buildbot-config', 07:09:10 INFO - 'download-and-extract', 07:09:10 INFO - 'populate-webroot', 07:09:10 INFO - 'create-virtualenv', 07:09:10 INFO - 'install', 07:09:10 INFO - 'run-tests'), 07:09:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:09:10 INFO - 'download_minidump_stackwalk': True, 07:09:10 INFO - 'download_symbols': 'ondemand', 07:09:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:09:10 INFO - 'tooltool.py': '/tools/tooltool.py', 07:09:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:09:10 INFO - '/tools/misc-python/virtualenv.py')}, 07:09:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:09:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:09:10 INFO - 'installer_path': 'installer.exe', 07:09:10 INFO - 'log_level': 'info', 07:09:10 INFO - 'log_name': 'talos', 07:09:10 INFO - 'log_to_console': True, 07:09:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:09:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:09:10 INFO - 'opt_config_files': (), 07:09:10 INFO - 'pip_index': False, 07:09:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:09:10 INFO - 'cmd': ('bash', 07:09:10 INFO - '-c', 07:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:09:10 INFO - 'enabled': True, 07:09:10 INFO - 'halt_on_failure': False, 07:09:10 INFO - 'name': 'check_screen_resolution'},), 07:09:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:09:10 INFO - 'cmd': ('bash', 07:09:10 INFO - '-c', 07:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:09:10 INFO - 'enabled': True, 07:09:10 INFO - 'halt_on_failure': False, 07:09:10 INFO - 'name': 'check_screen_resolution'},), 07:09:10 INFO - 'run_cmd_checks_enabled': True, 07:09:10 INFO - 'sps_profile': False, 07:09:10 INFO - 'sps_profile_interval': 0, 07:09:10 INFO - 'suite': 'dromaeojs-e10s', 07:09:10 INFO - 'system_bits': '32', 07:09:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:09:10 INFO - 'title': 't-yosemite-r7-0284', 07:09:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:09:10 INFO - 'use_talos_json': True, 07:09:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:09:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:09:10 INFO - 'work_dir': 'build'} 07:09:10 INFO - [mozharness: 2016-11-19 15:09:10.544180Z] Running clobber step. 07:09:10 INFO - Running pre-action listener: _resource_record_pre_action 07:09:10 INFO - Running main action method: clobber 07:09:10 INFO - rmtree: /builds/slave/test/build 07:09:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:09:12 INFO - Running post-action listener: _resource_record_post_action 07:09:12 INFO - [mozharness: 2016-11-19 15:09:12.846796Z] Finished clobber step (success) 07:09:12 INFO - [mozharness: 2016-11-19 15:09:12.847066Z] Running read-buildbot-config step. 07:09:12 INFO - Running pre-action listener: _resource_record_pre_action 07:09:12 INFO - Running main action method: read_buildbot_config 07:09:12 INFO - Using buildbot properties: 07:09:12 INFO - { 07:09:12 INFO - "project": "", 07:09:12 INFO - "product": "firefox", 07:09:12 INFO - "script_repo_revision": "production", 07:09:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:09:12 INFO - "repository": "", 07:09:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:09:12 INFO - "buildid": "20161119060822", 07:09:12 INFO - "pgo_build": "False", 07:09:12 INFO - "basedir": "/builds/slave/test", 07:09:12 INFO - "buildnumber": 34, 07:09:12 INFO - "slavename": "t-yosemite-r7-0284", 07:09:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:09:12 INFO - "platform": "yosemite_r7", 07:09:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:09:12 INFO - "branch": "mozilla-aurora", 07:09:12 INFO - "repo_path": "releases/mozilla-aurora", 07:09:12 INFO - "revision": "e59ed8b3ba13fd33435eea75da37ea6029c9af40", 07:09:12 INFO - "stage_platform": "macosx64", 07:09:12 INFO - "builduid": "dd4abdc43ab744439171eb6d5aa52e67", 07:09:12 INFO - "slavebuilddir": "test" 07:09:12 INFO - } 07:09:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:09:12 INFO - Running post-action listener: _resource_record_post_action 07:09:12 INFO - [mozharness: 2016-11-19 15:09:12.852672Z] Finished read-buildbot-config step (success) 07:09:12 INFO - [mozharness: 2016-11-19 15:09:12.852891Z] Running download-and-extract step. 07:09:12 INFO - Running pre-action listener: _resource_record_pre_action 07:09:12 INFO - Running main action method: download_and_extract 07:09:12 INFO - mkdir: /builds/slave/test/build/tests 07:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:12 INFO - trying https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:09:12 INFO - Downloading https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/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 07:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/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 07:09:13 INFO - Downloaded 1199 bytes. 07:09:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:09:13 INFO - Using the following test package requirements: 07:09:13 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:09:13 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:09:13 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:09:13 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'jsshell-mac.zip'], 07:09:13 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:09:13 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:09:13 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:09:13 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:09:13 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:09:13 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:09:13 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:09:13 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:09:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:09:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:09:13 INFO - Fetch https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:09:14 INFO - Http code: 200 07:09:14 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 07:09:14 INFO - x-amz-cf-id: oK31g-0o5Npd7Zq0FK7a285apIHY6bu7LbtLrKgqhpkGZj-4v5qKwQ== 07:09:14 INFO - x-amz-version-id: y5aDf08EuxDseNAB9MalrhXKasxw_MBE 07:09:14 INFO - Expected file size: 16274751 07:09:14 INFO - Obtained file size: 16274751 07:09:21 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 07:09:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:09:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:09:21 INFO - Fetch https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:09:21 INFO - Http code: 200 07:09:21 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 07:09:21 INFO - x-amz-cf-id: GaiyAsSln0INIToBCRwgG1kxgRAI6MwIjVPB3GxtEhbaX3GFoPwQhw== 07:09:21 INFO - x-amz-version-id: y5aDf08EuxDseNAB9MalrhXKasxw_MBE 07:09:22 INFO - Expected file size: 16274751 07:09:22 INFO - Obtained file size: 16274751 07:09:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:09:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:09:28 INFO - Fetch https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:09:29 INFO - Http code: 200 07:09:29 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 07:09:29 INFO - x-amz-cf-id: 0mpc0NAhWXPKItpsVbu9fAWyAVP7TvQifrPR-Hck8eWnfSsnPgJz-w== 07:09:29 INFO - x-amz-version-id: ccwj8gcACMwT0qtvBv3jGR7vAOXR88_U 07:09:30 INFO - Expected file size: 14409038 07:09:30 INFO - Obtained file size: 14409038 07:09:30 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')]} 07:09:30 INFO - trying https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/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 07:09:35 INFO - Downloaded 114492606 bytes. 07:09:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:09:35 INFO - mkdir: /builds/slave/test/properties 07:09:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:09:35 INFO - Writing to file /builds/slave/test/properties/build_url 07:09:35 INFO - Contents: 07:09:35 INFO - build_url:https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:09:35 INFO - Running post-action listener: _resource_record_post_action 07:09:35 INFO - Running post-action listener: set_extra_try_arguments 07:09:35 INFO - [mozharness: 2016-11-19 15:09:35.876659Z] Finished download-and-extract step (success) 07:09:35 INFO - [mozharness: 2016-11-19 15:09:35.876774Z] Running populate-webroot step. 07:09:35 INFO - Running pre-action listener: _resource_record_pre_action 07:09:35 INFO - Running main action method: populate_webroot 07:09:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:09:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:09:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:09:35 INFO - '--tppagecycles', 07:09:35 INFO - '7'], 07:09:35 INFO - 'tests': ['tsvgm']}}, 07:09:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:09:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:09:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:09:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:09:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['damp', 'tps']}, 07:09:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'tests': ['damp', 'tps']}, 07:09:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['dromaeo_dom']}, 07:09:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:09:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['basic_compositor_video']}, 07:09:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:09:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'other-e10s': {'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['a11yr', 07:09:35 INFO - 'ts_paint', 07:09:35 INFO - 'tpaint', 07:09:35 INFO - 'sessionrestore', 07:09:35 INFO - 'sessionrestore_no_auto_restore', 07:09:35 INFO - 'tabpaint']}, 07:09:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['tsvgx', 07:09:35 INFO - 'tsvgr_opacity', 07:09:35 INFO - 'tart', 07:09:35 INFO - 'tscrollx', 07:09:35 INFO - 'cart']}, 07:09:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:09:35 INFO - 'tsvgr_opacity', 07:09:35 INFO - 'tart', 07:09:35 INFO - 'tscrollx', 07:09:35 INFO - 'cart']}, 07:09:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'talos_options': ['--disable-e10s'], 07:09:35 INFO - 'tests': ['tp5o']}, 07:09:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'tests': ['tp5o']}, 07:09:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'talos_options': ['--disable-e10s', 07:09:35 INFO - '--xperf_path', 07:09:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:35 INFO - 'tests': ['tp5n']}, 07:09:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:35 INFO - 'talos_options': ['--xperf_path', 07:09:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:35 INFO - 'tests': ['tp5n']}}, 07:09:35 INFO - 'talos.zip': {'path': '', 07:09:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:09:35 INFO - Running post-action listener: _resource_record_post_action 07:09:35 INFO - [mozharness: 2016-11-19 15:09:35.893961Z] Finished populate-webroot step (success) 07:09:35 INFO - [mozharness: 2016-11-19 15:09:35.894099Z] Running create-virtualenv step. 07:09:35 INFO - Running pre-action listener: _resource_record_pre_action 07:09:35 INFO - Running main action method: create_virtualenv 07:09:35 INFO - Creating virtualenv /builds/slave/test/build/venv 07:09:35 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 07:09:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:09:36 INFO - Using real prefix '/tools/python27' 07:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:09:36 INFO - Installing distribute.............................................................................................................................................................................................done. 07:09:39 INFO - Installing pip.................done. 07:09:39 INFO - Return code: 0 07:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:09:39 INFO - Reading from file tmpfile_stdout 07:09:39 INFO - Output received: 07:09:39 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) 07:09:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:09:39 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')]} 07:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:39 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')]} 07:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:39 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:39 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 07:09:39 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 07:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:39 INFO - 'HOME': '/Users/cltbld', 07:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:39 INFO - 'LOGNAME': 'cltbld', 07:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:39 INFO - 'MOZ_NO_REMOTE': '1', 07:09:39 INFO - 'NO_EM_RESTART': '1', 07:09:39 INFO - 'PAGER': '/bin/cat', 07:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:39 INFO - 'PWD': '/builds/slave/test', 07:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:39 INFO - 'SHELL': '/bin/bash', 07:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:39 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:39 INFO - 'USER': 'cltbld', 07:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:39 INFO - 'XPC_FLAGS': '0x0', 07:09:39 INFO - 'XPC_SERVICE_NAME': '0', 07:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:40 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 07:09:40 INFO - Cleaning up... 07:09:40 INFO - Return code: 0 07:09:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:40 INFO - 'HOME': '/Users/cltbld', 07:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:40 INFO - 'LOGNAME': 'cltbld', 07:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:40 INFO - 'MOZ_NO_REMOTE': '1', 07:09:40 INFO - 'NO_EM_RESTART': '1', 07:09:40 INFO - 'PAGER': '/bin/cat', 07:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:40 INFO - 'PWD': '/builds/slave/test', 07:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:40 INFO - 'SHELL': '/bin/bash', 07:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:40 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:40 INFO - 'USER': 'cltbld', 07:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:40 INFO - 'XPC_FLAGS': '0x0', 07:09:40 INFO - 'XPC_SERVICE_NAME': '0', 07:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:40 INFO - Downloading/unpacking psutil>=3.1.1 07:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:09:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:44 INFO - Installing collected packages: psutil 07:09:44 INFO - Running setup.py install for psutil 07:09:44 INFO - building 'psutil._psutil_osx' extension 07:09:44 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 07:09:44 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 07:09:44 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 07:09:45 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 07:09:45 INFO - building 'psutil._psutil_posix' extension 07:09:45 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 07:09:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:09:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:09:45 INFO - ^ 07:09:45 INFO - 1 warning generated. 07:09:45 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 07:09:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:45 INFO - Successfully installed psutil 07:09:45 INFO - Cleaning up... 07:09:45 INFO - Return code: 0 07:09:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:09:45 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')]} 07:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:45 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')]} 07:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:45 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:45 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 07:09:45 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 07:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:45 INFO - 'HOME': '/Users/cltbld', 07:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:45 INFO - 'LOGNAME': 'cltbld', 07:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:45 INFO - 'MOZ_NO_REMOTE': '1', 07:09:45 INFO - 'NO_EM_RESTART': '1', 07:09:45 INFO - 'PAGER': '/bin/cat', 07:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:45 INFO - 'PWD': '/builds/slave/test', 07:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:45 INFO - 'SHELL': '/bin/bash', 07:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:45 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:45 INFO - 'USER': 'cltbld', 07:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:45 INFO - 'XPC_FLAGS': '0x0', 07:09:45 INFO - 'XPC_SERVICE_NAME': '0', 07:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:09:48 INFO - Installing collected packages: mozsystemmonitor 07:09:48 INFO - Running setup.py install for mozsystemmonitor 07:09:48 INFO - Successfully installed mozsystemmonitor 07:09:48 INFO - Cleaning up... 07:09:48 INFO - Return code: 0 07:09:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:48 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:48 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 07:09:48 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 07:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:48 INFO - 'HOME': '/Users/cltbld', 07:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:48 INFO - 'LOGNAME': 'cltbld', 07:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:48 INFO - 'MOZ_NO_REMOTE': '1', 07:09:48 INFO - 'NO_EM_RESTART': '1', 07:09:48 INFO - 'PAGER': '/bin/cat', 07:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:48 INFO - 'PWD': '/builds/slave/test', 07:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:48 INFO - 'SHELL': '/bin/bash', 07:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:48 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:48 INFO - 'USER': 'cltbld', 07:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:48 INFO - 'XPC_FLAGS': '0x0', 07:09:48 INFO - 'XPC_SERVICE_NAME': '0', 07:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:48 INFO - Downloading/unpacking jsonschema==2.5.1 07:09:48 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 07:09:48 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 07:09:48 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 07:09:48 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 07:09:48 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 07:09:48 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 07:09:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:09:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:09:51 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 07:09:51 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 07:09:51 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 07:09:51 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 07:09:51 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 07:09:51 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 07:09:51 INFO - Downloading functools32-3.2.3-2.tar.gz 07:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:09:51 INFO - warning: no files found matching '*.txt' 07:09:51 INFO - no previously-included directories found matching 'build' 07:09:51 INFO - no previously-included directories found matching 'dist' 07:09:51 INFO - no previously-included directories found matching '.git*' 07:09:52 INFO - Installing collected packages: jsonschema, functools32 07:09:52 INFO - Running setup.py install for functools32 07:09:52 INFO - warning: no files found matching '*.txt' 07:09:52 INFO - no previously-included directories found matching 'build' 07:09:52 INFO - no previously-included directories found matching 'dist' 07:09:52 INFO - no previously-included directories found matching '.git*' 07:09:52 INFO - Successfully installed jsonschema functools32 07:09:52 INFO - Cleaning up... 07:09:52 INFO - Return code: 0 07:09:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:52 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 07:09:52 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 07:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:52 INFO - 'HOME': '/Users/cltbld', 07:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:52 INFO - 'LOGNAME': 'cltbld', 07:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:52 INFO - 'MOZ_NO_REMOTE': '1', 07:09:52 INFO - 'NO_EM_RESTART': '1', 07:09:52 INFO - 'PAGER': '/bin/cat', 07:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:52 INFO - 'PWD': '/builds/slave/test', 07:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:52 INFO - 'SHELL': '/bin/bash', 07:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:52 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:52 INFO - 'USER': 'cltbld', 07:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:52 INFO - 'XPC_FLAGS': '0x0', 07:09:52 INFO - 'XPC_SERVICE_NAME': '0', 07:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:09:52 INFO - Cleaning up... 07:09:52 INFO - Return code: 0 07:09:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:52 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 07:09:52 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 07:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:52 INFO - 'HOME': '/Users/cltbld', 07:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:52 INFO - 'LOGNAME': 'cltbld', 07:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:52 INFO - 'MOZ_NO_REMOTE': '1', 07:09:52 INFO - 'NO_EM_RESTART': '1', 07:09:52 INFO - 'PAGER': '/bin/cat', 07:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:52 INFO - 'PWD': '/builds/slave/test', 07:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:52 INFO - 'SHELL': '/bin/bash', 07:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:52 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:52 INFO - 'USER': 'cltbld', 07:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:52 INFO - 'XPC_FLAGS': '0x0', 07:09:52 INFO - 'XPC_SERVICE_NAME': '0', 07:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:52 INFO - Downloading/unpacking blobuploader==1.2.4 07:09:52 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 07:09:52 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 07:09:52 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 07:09:52 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 07:09:52 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 07:09:52 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 07:09:55 INFO - Downloading blobuploader-1.2.4.tar.gz 07:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:09:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:09:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:09:56 INFO - Downloading docopt-0.6.1.tar.gz 07:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:09:56 INFO - Installing collected packages: blobuploader, requests, docopt 07:09:56 INFO - Running setup.py install for blobuploader 07:09:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:09:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:09:56 INFO - Running setup.py install for requests 07:09:57 INFO - Running setup.py install for docopt 07:09:57 INFO - Successfully installed blobuploader requests docopt 07:09:57 INFO - Cleaning up... 07:09:57 INFO - Return code: 0 07:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:57 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:09:57 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 07:09:57 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 07:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:57 INFO - 'HOME': '/Users/cltbld', 07:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:57 INFO - 'LOGNAME': 'cltbld', 07:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:57 INFO - 'MOZ_NO_REMOTE': '1', 07:09:57 INFO - 'NO_EM_RESTART': '1', 07:09:57 INFO - 'PAGER': '/bin/cat', 07:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:57 INFO - 'PWD': '/builds/slave/test', 07:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:57 INFO - 'SHELL': '/bin/bash', 07:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:09:57 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:57 INFO - 'USER': 'cltbld', 07:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:57 INFO - 'XPC_FLAGS': '0x0', 07:09:57 INFO - 'XPC_SERVICE_NAME': '0', 07:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-oRNWt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:57 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-fg5lE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-dREC3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-4YOLGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-70kNwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wz4f9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-SmAqbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-1R24l4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-rbDveA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-EqEo_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-WmvvwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:58 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-clhuDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-28MSVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-O55FDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-HoXn0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-YKtruK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Bc4d4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:09:59 INFO - Running setup.py install for manifestparser 07:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:09:59 INFO - Running setup.py install for mozcrash 07:09:59 INFO - Running setup.py install for mozdebug 07:10:00 INFO - Running setup.py install for mozdevice 07:10:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Running setup.py install for mozfile 07:10:00 INFO - Running setup.py install for mozhttpd 07:10:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Running setup.py install for mozinfo 07:10:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Running setup.py install for mozInstall 07:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:10:00 INFO - Running setup.py install for mozleak 07:10:00 INFO - Running setup.py install for mozlog 07:10:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Running setup.py install for moznetwork 07:10:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Running setup.py install for mozprocess 07:10:01 INFO - Running setup.py install for mozprofile 07:10:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Running setup.py install for mozrunner 07:10:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:10:01 INFO - Running setup.py install for mozscreenshot 07:10:01 INFO - Running setup.py install for moztest 07:10:02 INFO - Running setup.py install for mozversion 07:10:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:10:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:10:02 INFO - Cleaning up... 07:10:02 INFO - Return code: 0 07:10:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:02 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:10:02 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 07:10:02 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 07:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:02 INFO - 'HOME': '/Users/cltbld', 07:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:02 INFO - 'LOGNAME': 'cltbld', 07:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:02 INFO - 'MOZ_NO_REMOTE': '1', 07:10:02 INFO - 'NO_EM_RESTART': '1', 07:10:02 INFO - 'PAGER': '/bin/cat', 07:10:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:02 INFO - 'PWD': '/builds/slave/test', 07:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:02 INFO - 'SHELL': '/bin/bash', 07:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:10:02 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:02 INFO - 'USER': 'cltbld', 07:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:02 INFO - 'XPC_FLAGS': '0x0', 07:10:02 INFO - 'XPC_SERVICE_NAME': '0', 07:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:10:02 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-QblXYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:10:02 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)) 07:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:10:02 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-z24w3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:10:02 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)) 07:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:10:02 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wGeRrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:10:02 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)) 07:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:10:02 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-jFnWSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:10:02 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)) 07:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:10:02 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-FwVAIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-839LgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-QRgPLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-yE2qkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-o4tUZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-iohqNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-p3RtC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-7bNAiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-SqOPdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:10:03 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)) 07:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:10:03 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-0mRSSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:10:04 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)) 07:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:10:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-pHxZmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:10:04 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)) 07:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:10:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-lGJLYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:10:04 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)) 07:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:10:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-JUg3Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:10:04 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)) 07:10:04 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)) 07:10:04 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)) 07:10:04 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)) 07:10:04 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)) 07:10:04 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)) 07:10:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:10:04 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 07:10:04 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 07:10:04 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 07:10:04 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 07:10:04 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 07:10:04 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 07:10:06 INFO - Downloading blessings-1.6.tar.gz 07:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:10:06 INFO - Installing collected packages: blessings 07:10:06 INFO - Running setup.py install for blessings 07:10:07 INFO - Successfully installed blessings 07:10:07 INFO - Cleaning up... 07:10:07 INFO - Return code: 0 07:10:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:10:07 INFO - Reading from file tmpfile_stdout 07:10:07 INFO - Current package versions: 07:10:07 INFO - blessings == 1.6 07:10:07 INFO - blobuploader == 1.2.4 07:10:07 INFO - docopt == 0.6.1 07:10:07 INFO - functools32 == 3.2.3-2 07:10:07 INFO - jsonschema == 2.5.1 07:10:07 INFO - manifestparser == 1.1 07:10:07 INFO - mozInstall == 1.12 07:10:07 INFO - mozcrash == 1.0 07:10:07 INFO - mozdebug == 0.1 07:10:07 INFO - mozdevice == 0.48 07:10:07 INFO - mozfile == 1.2 07:10:07 INFO - mozhttpd == 0.7 07:10:07 INFO - mozinfo == 0.9 07:10:07 INFO - mozleak == 0.1 07:10:07 INFO - mozlog == 3.4 07:10:07 INFO - moznetwork == 0.27 07:10:07 INFO - mozprocess == 0.23 07:10:07 INFO - mozprofile == 0.28 07:10:07 INFO - mozrunner == 6.13 07:10:07 INFO - mozscreenshot == 0.1 07:10:07 INFO - mozsystemmonitor == 0.3 07:10:07 INFO - moztest == 0.7 07:10:07 INFO - mozversion == 1.4 07:10:07 INFO - psutil == 3.1.1 07:10:07 INFO - requests == 1.2.3 07:10:07 INFO - wsgiref == 0.1.2 07:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:07 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 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:10:07 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 07:10:07 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 07:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:07 INFO - 'HOME': '/Users/cltbld', 07:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:07 INFO - 'LOGNAME': 'cltbld', 07:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:07 INFO - 'MOZ_NO_REMOTE': '1', 07:10:07 INFO - 'NO_EM_RESTART': '1', 07:10:07 INFO - 'PAGER': '/bin/cat', 07:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:07 INFO - 'PWD': '/builds/slave/test', 07:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:07 INFO - 'SHELL': '/bin/bash', 07:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:10:07 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:07 INFO - 'USER': 'cltbld', 07:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:07 INFO - 'XPC_FLAGS': '0x0', 07:10:07 INFO - 'XPC_SERVICE_NAME': '0', 07:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 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)) 07:10:07 INFO - Cleaning up... 07:10:07 INFO - Return code: 0 07:10:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f52f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec11469320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, '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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 07:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:07 INFO - 'HOME': '/Users/cltbld', 07:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:07 INFO - 'LOGNAME': 'cltbld', 07:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:07 INFO - 'MOZ_NO_REMOTE': '1', 07:10:07 INFO - 'NO_EM_RESTART': '1', 07:10:07 INFO - 'PAGER': '/bin/cat', 07:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:07 INFO - 'PWD': '/builds/slave/test', 07:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:07 INFO - 'SHELL': '/bin/bash', 07:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:10:07 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:07 INFO - 'USER': 'cltbld', 07:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:07 INFO - 'XPC_FLAGS': '0x0', 07:10:07 INFO - 'XPC_SERVICE_NAME': '0', 07:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:10:08 INFO - Cleaning up... 07:10:08 INFO - Return code: 0 07:10:08 INFO - Running post-action listener: _resource_record_post_action 07:10:08 INFO - Running post-action listener: _start_resource_monitoring 07:10:08 INFO - Starting resource monitoring. 07:10:08 INFO - [mozharness: 2016-11-19 15:10:08.076984Z] Finished create-virtualenv step (success) 07:10:08 INFO - [mozharness: 2016-11-19 15:10:08.078023Z] Running install step. 07:10:08 INFO - Running pre-action listener: _resource_record_pre_action 07:10:08 INFO - Running main action method: install 07:10:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:10:08 INFO - Reading from file tmpfile_stdout 07:10:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:10:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:10:08 INFO - Reading from file tmpfile_stdout 07:10:08 INFO - Output received: 07:10:08 INFO - Usage: mozinstall [options] installer 07:10:08 INFO - Options: 07:10:08 INFO - -h, --help show this help message and exit 07:10:08 INFO - -d DEST, --destination=DEST 07:10:08 INFO - Directory to install application into. [default: 07:10:08 INFO - "/builds/slave/test"] 07:10:08 INFO - --app=APP Application being installed. [default: firefox] 07:10:08 INFO - mkdir: /builds/slave/test/build/application 07:10:08 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'] 07:10:08 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 07:10:39 INFO - Reading from file tmpfile_stdout 07:10:39 INFO - Output received: 07:10:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:10:39 INFO - Running post-action listener: _resource_record_post_action 07:10:39 INFO - [mozharness: 2016-11-19 15:10:39.790315Z] Finished install step (success) 07:10:39 INFO - [mozharness: 2016-11-19 15:10:39.790448Z] Running run-tests step. 07:10:39 INFO - Running pre-action listener: _resource_record_pre_action 07:10:39 INFO - Running main action method: run_tests 07:10:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:10:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:10:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:10:39 INFO - 2016-11-19 07:10:39.803 screenresolution[1634:7914] starting screenresolution argv=screenresolution get 07:10:39 INFO - 2016-11-19 07:10:39.806 screenresolution[1634:7914] Display 0: 1600x1200x32@60 07:10:39 INFO - 2016-11-19 07:10:39.811 screenresolution[1635:7916] starting screenresolution argv=screenresolution list 07:10:39 INFO - Available Modes on Display 0 07:10:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:10:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:10:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:10:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:10:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:10:40 INFO - Intel Iris: 07:10:40 INFO - Chipset Model: Intel Iris 07:10:40 INFO - Type: GPU 07:10:40 INFO - Bus: Built-In 07:10:40 INFO - VRAM (Dynamic, Max): 1536 MB 07:10:40 INFO - Vendor: Intel (0x8086) 07:10:40 INFO - Device ID: 0x0a2e 07:10:40 INFO - Revision ID: 0x0009 07:10:40 INFO - Displays: 07:10:40 INFO - Display: 07:10:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:10:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:10:40 INFO - Display Serial Number: 1600x1200 60 07:10:40 INFO - Main Display: Yes 07:10:40 INFO - Mirror: Off 07:10:40 INFO - Online: Yes 07:10:40 INFO - Rotation: Supported 07:10:40 INFO - Return code: 0 07:10:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:10:40 INFO - Python 2.7.3 07:10:40 INFO - Return code: 0 07:10:40 INFO - grabbing minidump binary from tooltool 07:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:40 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 0x10f3cdd30>, '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 0x10f233db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:10:40 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 07:10:40 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 07:10:40 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 07:10:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:10:40 INFO - Return code: 0 07:10:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:10:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:10:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:10:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:10:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:10:40 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:10:40 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0284 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:10:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xH9fOLfT5r/Render', 07:10:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:40 INFO - 'HOME': '/Users/cltbld', 07:10:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:40 INFO - 'LOGNAME': 'cltbld', 07:10:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:10:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:40 INFO - 'MOZ_NO_REMOTE': '1', 07:10:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:10:40 INFO - 'NO_EM_RESTART': '1', 07:10:40 INFO - 'PAGER': '/bin/cat', 07:10:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:40 INFO - 'PWD': '/builds/slave/test', 07:10:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:10:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:40 INFO - 'SHELL': '/bin/bash', 07:10:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hb6oTgO3Cf/Listeners', 07:10:40 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 07:10:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:40 INFO - 'USER': 'cltbld', 07:10:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:40 INFO - 'XPC_FLAGS': '0x0', 07:10:40 INFO - 'XPC_SERVICE_NAME': '0', 07:10:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:10:40 INFO - mozversion application_buildid: 20161119060822 07:10:40 INFO - mozversion application_changeset: e59ed8b3ba13fd33435eea75da37ea6029c9af40 07:10:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:10:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:10:40 INFO - mozversion application_name: Firefox 07:10:40 INFO - mozversion application_remotingname: firefox-dev 07:10:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:10:40 INFO - mozversion application_vendor: Mozilla 07:10:40 INFO - mozversion application_version: 52.0a2 07:10:40 INFO - mozversion platform_buildid: 20161119060822 07:10:40 INFO - mozversion platform_changeset: e59ed8b3ba13fd33435eea75da37ea6029c9af40 07:10:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:10:40 INFO - mozversion platform_version: 52.0a2 07:10:40 INFO - using testdate: 1479568240 07:10:40 INFO - actual date: 1479568240 07:10:40 INFO - starting webserver on 'localhost:49242' 07:10:40 INFO - SUITE-START | Running 2 tests 07:10:40 INFO - TEST-START | dromaeo_css 07:10:40 INFO - operating with platform_type : mac_ 07:10:40 INFO - Initialising browser for dromaeo_css test... 07:10:40 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpwOJ17M/profile http://localhost:49242/getInfo.html) 07:10:42 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:10:43 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 07:10:43 INFO - PROCESS | 1645 | colorDepth:24 07:10:43 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 07:10:43 INFO - PROCESS | 1645 | __metrics 07:10:43 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 07:10:44 INFO - TEST-INFO | 1645: exit 0 07:10:44 INFO - Browser initialized. 07:10:44 INFO - Running cycle 1/1 for dromaeo_css test... 07:10:44 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpwOJ17M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:10:56 INFO - PROCESS | 1649 | RSS: Main: 224129024 07:10:56 INFO - PROCESS | 1649 | 07:11:45 INFO - PROCESS | 1649 | 1479568305776 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] 07:13:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 07:14:01 INFO - PROCESS | 1649 | RSS: Main: 233684992 07:14:01 INFO - PROCESS | 1649 | 07:17:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 07:17:04 INFO - PROCESS | 1649 | RSS: Main: 244908032 07:17:04 INFO - PROCESS | 1649 | 07:20:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 07:20:07 INFO - PROCESS | 1649 | RSS: Main: 248840192 07:20:07 INFO - PROCESS | 1649 | 07:23:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 07:23:09 INFO - PROCESS | 1649 | RSS: Main: 247996416 07:23:09 INFO - PROCESS | 1649 | 07:26:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 07:26:13 INFO - PROCESS | 1649 | RSS: Main: 251576320 07:26:13 INFO - PROCESS | 1649 | 07:29:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 07:29:16 INFO - PROCESS | 1649 | RSS: Main: 252608512 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | __start_tp_report 07:29:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 07:29:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 07:29:16 INFO - PROCESS | 1649 | |i|pagename|runs| 07:29:16 INFO - PROCESS | 1649 | |0;dojo.html;3367.629560748319;3379.746519011074;3403.4894765785143;3403.948940765889;3414.5731783527053;5619;5699.458551437617;5721.227634269372;5727.713614319277;5779.479846813786;5711.286089238845;5724.7996320128805;5749.3388260350075;5770.624909380887;5822.330432000321;5227.32044834171;5242.606804489659;5261.710605916673;5276.314079169702;5291.735413229342;5256.92114618281;5290.65610735301;5319.122344813101;5322.946770532303;5338.91991620126;5161.0968080585935;5198.532132108109;5288.365396152467;5303.761330740107;5325.520703136717;5192.1432963560965;5206.661566998152;5261.973690131525;5279.313689220377;5300.1519756838725;5193.792248310063;5210.192420174879;5232;5246.842594722164;5336.653117547347;5531.948929703343;5756.079027355603;5789.45000224978;5838.708064596795;5848.064309710426;6029.457348838626;6131.509479241695;6139.829471381233;6153.292371377304;6180.845478863064;6254.437100660963;6272.968635156795;6283.340249273851;6326.114343991845;6338.746450141989;5845.123231515222;5853.619514731528;5870.26621672291;5895.263092113486;5905.46850783423;6157.322694503601;6177.4749146322165;6190.226221722285;6257.186565746515;6268.153799237136;5944.7027648617395;5953.613015154001;5977.282726072899;5981.521478281814;5983.461488466059;6073.848153796191;6116.724747386378;6123.387661233841;6148.1392605035335;6158.846028849225;6107.664078475637;6108.949222962805;6145.754169833157;6173.753049878044;6232.439080482778;6162.198914141133;6204;6245.188125543741;6250.374962503804;6253.53098517613;4895.926561101586;4917.680350777188;4921.8523444296725;4935.457099719028;4942.159832828428;4341.696081274281;4351.173277077409;4354.651627869763;4366.235908715962;4373.169097871394;4745.881352966204;4785.353977213102;5780.306363236438;5791.102379131242;5832.562557808182;6163.9383606163365;6255.186825712628;6256.405641464054;6260.24877014752;6261.843453913689;5785.392533783976;5810.273715785527;5865.384134665884;5896.93855106085;6009.729562169713;5398.055340315507;5411.675299481965;5411.675299482044;5423.376311724202;5486.807961721401;5553.361363443256;6289.7484100635465;6303.1175635412;6308.747650093945;6316.684165791692;6112.685862051923;6156.168917196301;6183.659898705434;6207.96896015517;6225.3774622537385;6282.3089460158435;6288.654124023223;6305.684715764101;6313.747450101944;6339.049142628643;6239.438450539473;6250.499960003098;6266;6266.905996410149;6285.622862628257;6094.329623741473;6142.007694998287;6167.105769663463;6189.0097584386285;6310.936890631035;4529.754317562565;5997.500624843789;6045.667488288011;6131.223750760645;6143.542582966849;6118.969405152946;6120.724567394389;6130.601510901955;6151.5386346025125;6151.984922487918;5715.085586306171;5750.022496175577;5856.150858125633;5857;5989.700514974321;6026.487748541248;6066.7269972850445;6079.544034197364;6099.8780024398375;6136.549713441251;5433.695912980935;6005.249343832021;6011.098335249748;6019.9465093609315;6028.547858910511 07:29:16 INFO - PROCESS | 1649 | |1;ext.html;462.57674228081305;518.823599976008;526.6918852471302;539.8812261302512;543.2096299883669;30282.394352112973;39076.609233907686;40315.59684403155;40521.1895762085;42849;32687.67312326876;37695.811520942356;38864.02839929003;41844.372334415006;42407.36388954161;8094.392920530954;9277.675281365153;9291.163795258426;9447.952760236189;9458.101480359355;9405.529723513831;9469.763255918588;9479.620815167385;9496.335256532046;9532.618695252217;9440.905590944105;9464.574094165779;9548.713538593853;9552.80894382112;9559.13967742903;9451;9491.19324857388;9510.001449847805;9511.286653501014;9569.473678947656;9343.065693430603;9384.483853388061;9412.341136120474;9413.57638906251;9425;39695.60304396948;39920.401193982114;39925.20149597021;39993.80003099995;40132.996675083064;2885.5094633912286;3302.2204070248054;3666.724995625312;3803.5245594300713;3940.7241493095494;29010;29092.836286548725;29144.854275728696;29685.9609913652;29922.653480593424;3084.3214492811544;3134.200778801414;3159.368126374711;3267.852946617384;3305.4876494143446;496571;498232;505128;506020;507600.99999999633;53411.46588534065;60296.69851650801;60511.78976420447;60611.69694151501;60721;51532.484675152766;62290.68854655698;62516;63097.36902630915;63115.68442157852;15761.921190394203;16087;16275.593110172103;16351.100689461955;16362;12543;12653.493860245488;12677.619671409873;12687.429065692066;12737.745245095046;16316.75524867128;16521.66956660884;16640.83359166417;16661.583460413585;16699.91650041742;3119.7348225401097;5782.88434231313;5998.880022399528;6016.548758843104;6086.482648974798;864.7449002544139;1000.6997900629782;1046.7016900183412;1051.8842927277992;1071.8660167479065;30036.849815750782;30119;30164.54753178704;30530.84734576313;30631.69368306333;857.6012154348133;954.8185844689486;1018.1701912940929;1054.6941386997837;1108.778244351133;14869.330880110421;15286.464973725866;15354.155521446428;15417.614559636098;15518.69274572914;665735;667736;670296;670355;675869;55953.16070258949;57653.42346576564;58033.4196658028;58050.709746451;58288.41711582915;1328.4154971812372;1343.7379710956466;1361.6255529729206;1366.3031853754658;1366.4261010375667;25625.10312139066;29158;29639.703602963695;29929;30217.244568885075;35953.64046359608;40960.59039409687;49887.251691225385;50748;50855;556769;634708.4793728191;637454;641253;644103;25808.96764129489;26099.347516312246;26217.344566385993;26385.60421593716;26637.801298941205;45128.774356129325;45969.31046034245;46174.92212850497;46184.53815461937;46376.536234638574;38154.236915262096;38354;38934.61065389423;39428.2114357717;39680.60319396769;37967.430488542144;38396;39142.412863806494;39200.29913997258;40049.79975100106;31213.8439307802;37237.255254895295;37239;37525.6247437533;37725.81137094297;32827.67172328342;34149.65850341465;34190.80332188361;34664.30671386508;34980.82509587436;25769;25805.870970645028;25897.482050359264;25952;26302.079427219956 07:29:16 INFO - PROCESS | 1649 | |2;jquery.html;373.9121306492974;376.394005650902;389.9512560929884;394.57977254224267;405.17546792277705;17790.82209177908;18905;19626.018699065044;19766.11052502637;19814.504637384074;18634.627307453844;18920.621587568276;19018.049097545136;19159.61680766387;19553.924534150618;5351.812686555972;5415.943890941268;5480.835574932748;5758.481736643701;5771.422857714237;5558.833235002957;5721.170430287606;5723.141528770676;5735.967525845346;5750.568707346944;5461.972690136544;5675.432456754313;5688.687122208277;5722.113072473773;5773.855653608652;5772.393898640625;5823.184754134425;5873.500752436048;5874.970625146847;5897.115432685088;5516.282883225155;5680.204771332017;5690.118031705092;5717.228174196472;5719.914201286984;12444.253344799341;12686.619401417973;12743.107982441234;12994.090413671047;13060.542880999119;2243.14760391052;2305.5734689082574;2410.819188560865;2602.557565213918;2825.3642930340716;9902.801943961153;10106.595736170619;10117.797644047077;10295.79408411835;14864.108153510824;1584.6830633873271;1762.189392879277;1799.1723807048643;1806.692862213427;1823.216017112641;109103.45448272787;114619;120559;121286.34916191346;122202.35040925432;9557.952210238904;10928.672139835659;11181.608643697595;11315.603953861575;11535;14188;14303.64240893986;14310.141391516543;14323.641908952359;14329.85670143306;7056;8347.540885251248;8433.19884610961;8468.957655211685;8578.399512034222;5931.525477961753;6465.321141280192;7761.573113478813;8097.757067287991;8362.623681934327;4293.463317085364;4454.7549884756645;4460.241758900995;4531.13908357411;4535.7732113394195;1599.6882556303951;1867.3930972434014;1913.521989024217;1920.9135588898253;1923.3364489251187;867.7223288547605;896.8744375787395;921.1433366968649;940.4310392212612;992.1120597065586;19710.802891971183;20360.490987725425;20412;20591.8970405147;21262.362129136152;909.6497848328441;929.3494553812359;942.962281508732;959.5729900194347;998.4259051045533;10249.538770755335;10716.571337146583;10743.301685390425;10881.673549793519;10887;586138;592704;599208;601019;602275;120429.39785301017;122759;125714;129586;131060.34469827781;873.2490058549643;918.1461241045891;933.5239028095721;936.5317341329335;938.1134827588;17681.469555913343;17708;18010.189541470405;18083.54791130232;18182.181801818686;44446.55553444424;48904.35408636174;52057.21914171215;52246.73876630593;52699.94600108053;73124.63437682777;74886.62556687182;77714;80359.19640803518;80671;6656.301088385843;7566.735164269224;7578.393728501817;7636.160022397421;7723;11069.391183484664;11953.402329883646;12010.519579217083;12092;12335.568255111028;10091.091801737879;10351.741206469898;10361.792764144524;10419.739506512398;10668.62659806934;9232.538373081454;9653.276004299565;10202.438865862448;10413.531391087268;10492.52783624754;10122.291439599157;10224.591016359262;10795.6221532246;11008.559657613927;11042.503087360927;7654.387648987956;7766.961165194138;7782.571958542108;7885.974823272938;7919.049714034353;7939.960300198462;9104.18062374363;10591.417472039111;10922.453877305945;11342.092632589538 07:29:16 INFO - PROCESS | 1649 | |3;mootools.html;1463.3195564062712;1506.7137243923655;1598.7529726813082;1693.5173475559468;1747.9475615731533;7584.355329796974;7851.097123830761;8183.91816081839;8622.09468005859;8670.393072484929;8105.392095592824;8376.3717721171;8413.074561798197;8510.404271700983;8522.446041007346;2316.200910685815;2343.4727186383097;2354.1878052072093;2360.5042940982416;2361.53949979754;2347.14329269817;2380.988095059531;2401.9639705404347;2402.087206861399;2407.38611654028;1994.212286146971;2319.7100362454694;2322.094383190557;2342.156823543523;2367.9763202368013;2052.938411847647;2257.656694266917;2301.6547517872286;2317.409060689522;2350.3066595354326;2366.1600131953082;2380.8690522021284;2383.5590415772963;2387.5344307859973;2388.3432056184515;8463.069062403192;8464.619092140869;8552.102029286898;8573.099824518398;8624.568771561397;5137.12668846297;5303.443138470444;5308.946910530923;5310.309659744247;5317.122674758699;3351.916202094967;3470.9066644007307;3674.779513229215;3694.261147770457;3700.4264339027495;1022.4580972084807;1059.978800423995;1065.248999455382;1110.5835311758092;1111.261011427408;11050.281731687415;11075.612353567587;11169.664910052712;11244.887551124548;11251.43742812856;4535.841245556389;4685.718856868531;4743.739094349775;4773.11697335994;4820.060088282821;9349.766255843522;9458.763530911645;9471.573779179953;9494.240460763122;9507;5236.450172731808;5257.158854583248;5267.183586544092;5270.183121616155;5281.498257665592;4325.2214601371425;4356.912861742748;4788.569028787426;4837.975810120997;4842.903141937141;3315.8063097284958;3810.0855794609092;3870.903227419281;3910.9413358799643;3975.0857302820514;1081.351189286422;1253.404632799413;1275.0819410024767;1277.3549357574366;1288.7229245712217;754.8150703077781;795.4471642208725;886.9423487473293;903.9683611073581;921.9585118669676;5636.689982050944;5660.29246344207;5748.080307150836;5808.867270882222;5945.048792193228;761.3604572159302;881.9823603527893;918.3846822628722;938.0806809326898;942.3733217410345;3177.459831828595;3250.902472925816;3837.5394952605866;3915.169670578181;3933.7049721270446;10016.348937319051;10475.528601212964;10482.21383396233;10494.422806745702;10519.211059170593;8519.872201916976;10184;10299.897001030044;10358.430286334324;10403.84394234087;7804.726834560764;7820.335271501986;7951;7989.800254993555;8021.8395632088195;5492.47821456961;5542.168674698827;5604.159376093456;5629.099344104923;5737.08206686928;7946.801329966797;9516.38143520669;9627.181689556466;9630.759231019281;9643.807123857623;8901.51041692713;9120.224780893699;9757.463339516395;9766.21870250364;9901.504924753877;5959.135925290895;6246.719422518464;6292.150559674568;6789.1852977643;6888.311168883071;6868.587884726932;7781;8054.395920305883;8087.959560202161;8108.918910811052;7003.5797852129035;7105.32499412555;7124.465665075037;7150.213476517475;7243.963780181276;7068.893966590608;7094.4679149062995;7098.112735908012;7108.786736397917;7204.423646108194;6731.427828634621;6778.8983165251575;6805.6937437816405;6822.420094291842;6845.418139458202;4887.242477416006;4890.364252647133;4895.314655948099;4912.410510738591;4965.553100220853;4880.511948805091;4920.6555541111775;4952.7771250294545;4981.576565991786;5033.370828646419 07:29:16 INFO - PROCESS | 1649 | |4;prototype.html;300.7413624283116;310.3173019357414;312.2490410562597;315.8231390421364;317.303518776286;4974.950250497498;5231.659942103761;5347.25138480613;5373.731313434328;5522.613417060812;4317.373980772794;5119.1809310510325;5309.8938021239555;5336.67979921205;5341.866453338668;3710.12811989183;3917.1773927475265;4083.4895638045246;4109.732867363627;4113.773742444165;3409.3863104641155;3472.8437220325145;4016.096378314885;4097.201045796071;4113.444684967521;3977.5624681285035;4001.5798341174195;4022.9597704022876;4034.7780872052026;4082.265192265391;3503.964960350415;3952.130531283113;4030.193961207741;4051.534073581546;4096.774677392742;3551.449525323579;3552.8223588820456;3562.447820587813;3566.7859928404378;3583.372909740785;4279.486461624594;4918.9754051229875;5141.974290128563;5147.679746563483;5160.200168973815;3864.7101467389775;3974.9006274843073;3990.980045099785;4040.4747382840337;4623.190941585242;5561.5272701820395;5637.830865074055;5647.548196144298;5647.943520564824;5701.543876489871;3190.4416727072903;3424.9486257706153;3588.5334906462253;3852.7110466715108;3928.3893603622664;5859.707014649293;5861.970690146564;5901.911471327933;5956.255468066492;5964.284285885721;3606.422972324468;3769.4345848123003;3907.4529565860803;3936.3111455495173;3939.3697008478507;3818.7708737475286;3941.0935484838074;3977.125032492847;3979.144483935968;3986.1828325193264;3772.603876592973;4516.977415112903;4552.658550608718;4611.838585649486;4626.306054091913;5202.869928251824;5661.971690141605;5665.971670141623;5687.7156142192725;5700.945325114867;1055.6885718712997;1147.185498296219;1200.903927685783;1211.763706077324;1276.3554405025402;803.7381311341138;804.7124600639048;852.6120615120105;876.7106854738049;878.5343767803074;5107.846764597068;5109.846704598868;5132.177302483619;5151.948480515147;5153.711572106973;4676.95323046772;5133.383993920754;5162.483751624883;5495.395506494362;5600.467955544217;4675.978658353813;4692.846830554915;4794.523970121634;5036.7733451994745;5109.642325037212;2661.0973154194844;2768.6123942648046;2769.587510369726;2873.827570345786;2897.5219088850104;5023.447420783711;5288.550473209821;5332.706701131475;5615.747291371898;5673;4520.61574766142;5182.714950677673;5242.6592271503;5658.943410565924;5685.772569097273;5394.487523685244;5428.999999999921;5658.660480371191;5661.961693790232;5705.258316418839;5453.727313634382;5465;5837.737301821343;5918.852028699144;5937.524998000236;4972.8756781080765;5083;5844.503217226414;5866.50134738552;5867.530597552271;4959.553640172402;5754.525554011815;5968.015277479341;6060.212172417734;6090.847728806815;3110.440120778191;3836.582952739756;3878.789090218661;3925.391564307537;3980.7611543307494;4759.833405830917;5616.494515493625;5861.9413805863105;5876.706164691663;5900.7049647518315;5558.749856256559;5594.812495377073;5627.839632532762;5723.656580605177;5724.542036637143;4299.763513006815;4366.476022877275;5369.812056578001;5626.774929002958;5699.857503562444;5297.867553311198;5339.97081109176;5445.074337362737;5596.8937239832285;5622.6345287557815;4818.386579634686;5291.206319052173;5650.0677388232125;5673.773039085908;5772.855678608068;4731.6214702823;5316.208990280134;5541.418151094077;5608.934302482516;5611.80358687444 07:29:16 INFO - PROCESS | 1649 | |5;yui.html;636.8409494719613;677.9966100169498;719.1980941250507;764.2586690909816;784.3019712455913;13755.79366309506;14444.5666630001;14487.637809054779;14499.057561258538;14656.853431465683;12928.224306541591;13010.284434356106;13047.282399468026;14276;14852.851471485283;652.5301782716449;673.8753330633824;799.6201804143014;814.7474282972297;826.6404114210308;685.8559702462464;819.7008092046419;826.9421140520166;827.0117209934101;827.1149869639488;837.0792128658488;868.0061329777401;869.2394155114275;870.3689824876977;875.5840975536632;857.3398483167925;861.2076889261895;861.2119910282115;862.4351050062219;862.633380813155;635.0601110356652;635.1013316157631;637.2544123375661;637.4103953842723;637.9138816259792;13958.371873265727;14004.859951400458;14200.289985500684;14216.573502794932;14252.786208206886;11109.944450277697;13344.666383340495;13534.323283835769;13659.618262542685;13763.174209547458;11065.668029959113;11416.885831141748;11436.542538298458;11460.197786154973;11927.343996080212;3517.384457719889;3620.239749652576;3677.595464498903;3681.907952301187;3703.407454807245;93448.53275733646;94201;95554.56668149984;95808.52095739616;96549.5172524133;10165.94917025425;10217.948910255402;10257.230707696952;10291.228157888188;10319.948400258101;20080.49798755043;20122.295719650036;20279.898600506902;20335.898320508597;20813.79186208149;7543.9622801886735;7582.128055273603;7594.31651151388;7634.885476717854;7753.991981042428;3480.4083305838067;3570.285942811448;4122.42286079949;4236.364545318227;4249.978750106292;3360.1431634932937;3471.531343268677;3493.1965647900615;3590.982045089758;3769.7738135711943;675.4767998164109;694.1323345817727;695.6104581434413;696.8432102776835;706.8939659051183;559.636236446305;581.1486172756931;628.1833616298793;651.4299987510927;669.1334721535623;9078.636854525745;9200.95399522998;9223.44659320443;9399.530023498934;9456.57445414958;511.71855479486135;516.8914527949098;568.5196009372074;625.0811307378146;694.2432748304271;4188.979055104766;4335.9349609755245;4440.134173836135;4470.396496472999;4538.795754191069;72566.63716681383;77882;110279.79440411147;111673;111837.44081279541;62562.68718656468;68263.97604035944;70845.64577177081;90540;94927;75892.34376796786;84522;85050;88472;89157;6804.9319506804295;8559.871601925852;8866.86699699492;8926.094709728346;9111.043340449423;43922.560774391844;55870.44129558653;57688.4231157683;58146;58168.54578635568;56099.71950140223;81039;81731.59134204291;82617;82667.17332826594;9124.543772811465;9752.317337786288;10734.86494414303;10810.45947702596;11004.614838480615;12125.514979400725;12288.692782680504;12329.383530823603;12494;12702.872971270539;12593.118481706559;12713.237205767684;12861.935690321488;13488;14029.719405611626;11856.940715296369;12940.223586584834;13745.312734363042;13837.861621383658;14132;12444.942179914446;12818.67953301175;13234.007449441138;13254.072214944861;13698.452061917811;9924.305298629026;10823;11217.102631789601;11221.102311814871;11549.6535103947;9614.086661767122;10109.898901011189;11137.721556960816;11314;11430.942845285721 07:29:16 INFO - PROCESS | 1649 | __end_tp_report 07:29:16 INFO - PROCESS | 1649 | __start_cc_report 07:29:16 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,362 07:29:16 INFO - PROCESS | 1649 | __end_cc_report 07:29:16 INFO - PROCESS | 1649 | __startTimestamp1479569356007__endTimestamp 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | ------- Summary: start ------- 07:29:16 INFO - PROCESS | 1649 | Number of tests: 6 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5724.41 Median:5953.61 stddev:637.34 (10.7%) stddev-sans-first:611.89 07:29:16 INFO - PROCESS | 1649 | Values: 3367.6 3379.7 3403.5 3403.9 3414.6 5619.0 5699.5 5721.2 5727.7 5779.5 5711.3 5724.8 5749.3 5770.6 5822.3 5227.3 5242.6 5261.7 5276.3 5291.7 5256.9 5290.7 5319.1 5322.9 5338.9 5161.1 5198.5 5288.4 5303.8 5325.5 5192.1 5206.7 5262.0 5279.3 5300.2 5193.8 5210.2 5232.0 5246.8 5336.7 5531.9 5756.1 5789.5 5838.7 5848.1 6029.5 6131.5 6139.8 6153.3 6180.8 6254.4 6273.0 6283.3 6326.1 6338.7 5845.1 5853.6 5870.3 5895.3 5905.5 6157.3 6177.5 6190.2 6257.2 6268.2 5944.7 5953.6 5977.3 5981.5 5983.5 6073.8 6116.7 6123.4 6148.1 6158.8 6107.7 6108.9 6145.8 6173.8 6232.4 6162.2 6204.0 6245.2 6250.4 6253.5 4895.9 4917.7 4921.9 4935.5 4942.2 4341.7 4351.2 4354.7 4366.2 4373.2 4745.9 4785.4 5780.3 5791.1 5832.6 6163.9 6255.2 6256.4 6260.2 6261.8 5785.4 5810.3 5865.4 5896.9 6009.7 5398.1 5411.7 5411.7 5423.4 5486.8 5553.4 6289.7 6303.1 6308.7 6316.7 6112.7 6156.2 6183.7 6208.0 6225.4 6282.3 6288.7 6305.7 6313.7 6339.0 6239.4 6250.5 6266.0 6266.9 6285.6 6094.3 6142.0 6167.1 6189.0 6310.9 4529.8 5997.5 6045.7 6131.2 6143.5 6119.0 6120.7 6130.6 6151.5 6152.0 5715.1 5750.0 5856.2 5857.0 5989.7 6026.5 6066.7 6079.5 6099.9 6136.5 5433.7 6005.2 6011.1 6019.9 6028.5 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:72064.49 Median:21684.47 stddev:161523.92 (744.9%) stddev-sans-first:161887.57 07:29:16 INFO - PROCESS | 1649 | Values: 462.6 518.8 526.7 539.9 543.2 30282.4 39076.6 40315.6 40521.2 42849.0 32687.7 37695.8 38864.0 41844.4 42407.4 8094.4 9277.7 9291.2 9448.0 9458.1 9405.5 9469.8 9479.6 9496.3 9532.6 9440.9 9464.6 9548.7 9552.8 9559.1 9451.0 9491.2 9510.0 9511.3 9569.5 9343.1 9384.5 9412.3 9413.6 9425.0 39695.6 39920.4 39925.2 39993.8 40133.0 2885.5 3302.2 3666.7 3803.5 3940.7 29010.0 29092.8 29144.9 29686.0 29922.7 3084.3 3134.2 3159.4 3267.9 3305.5 496571.0 498232.0 505128.0 506020.0 507601.0 53411.5 60296.7 60511.8 60611.7 60721.0 51532.5 62290.7 62516.0 63097.4 63115.7 15761.9 16087.0 16275.6 16351.1 16362.0 12543.0 12653.5 12677.6 12687.4 12737.7 16316.8 16521.7 16640.8 16661.6 16699.9 3119.7 5782.9 5998.9 6016.5 6086.5 864.7 1000.7 1046.7 1051.9 1071.9 30036.8 30119.0 30164.5 30530.8 30631.7 857.6 954.8 1018.2 1054.7 1108.8 14869.3 15286.5 15354.2 15417.6 15518.7 665735.0 667736.0 670296.0 670355.0 675869.0 55953.2 57653.4 58033.4 58050.7 58288.4 1328.4 1343.7 1361.6 1366.3 1366.4 25625.1 29158.0 29639.7 29929.0 30217.2 35953.6 40960.6 49887.3 50748.0 50855.0 556769.0 634708.5 637454.0 641253.0 644103.0 25809.0 26099.3 26217.3 26385.6 26637.8 45128.8 45969.3 46174.9 46184.5 46376.5 38154.2 38354.0 38934.6 39428.2 39680.6 37967.4 38396.0 39142.4 39200.3 40049.8 31213.8 37237.3 37239.0 37525.6 37725.8 32827.7 34149.7 34190.8 34664.3 34980.8 25769.0 25805.9 25897.5 25952.0 26302.1 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34147.02 Median:383.17 stddev:99582.58 (25989.0%) stddev-sans-first:99829.65 07:29:16 INFO - PROCESS | 1649 | Values: 373.9 376.4 390.0 394.6 405.2 17790.8 18905.0 19626.0 19766.1 19814.5 18634.6 18920.6 19018.0 19159.6 19553.9 5351.8 5415.9 5480.8 5758.5 5771.4 5558.8 5721.2 5723.1 5736.0 5750.6 5462.0 5675.4 5688.7 5722.1 5773.9 5772.4 5823.2 5873.5 5875.0 5897.1 5516.3 5680.2 5690.1 5717.2 5719.9 12444.3 12686.6 12743.1 12994.1 13060.5 2243.1 2305.6 2410.8 2602.6 2825.4 9902.8 10106.6 10117.8 10295.8 14864.1 1584.7 1762.2 1799.2 1806.7 1823.2 109103.5 114619.0 120559.0 121286.3 122202.4 9558.0 10928.7 11181.6 11315.6 11535.0 14188.0 14303.6 14310.1 14323.6 14329.9 7056.0 8347.5 8433.2 8469.0 8578.4 5931.5 6465.3 7761.6 8097.8 8362.6 4293.5 4454.8 4460.2 4531.1 4535.8 1599.7 1867.4 1913.5 1920.9 1923.3 867.7 896.9 921.1 940.4 992.1 19710.8 20360.5 20412.0 20591.9 21262.4 909.6 929.3 943.0 959.6 998.4 10249.5 10716.6 10743.3 10881.7 10887.0 586138.0 592704.0 599208.0 601019.0 602275.0 120429.4 122759.0 125714.0 129586.0 131060.3 873.2 918.1 933.5 936.5 938.1 17681.5 17708.0 18010.2 18083.5 18182.2 44446.6 48904.4 52057.2 52246.7 52699.9 73124.6 74886.6 77714.0 80359.2 80671.0 6656.3 7566.7 7578.4 7636.2 7723.0 11069.4 11953.4 12010.5 12092.0 12335.6 10091.1 10351.7 10361.8 10419.7 10668.6 9232.5 9653.3 10202.4 10413.5 10492.5 10122.3 10224.6 10795.6 11008.6 11042.5 7654.4 7767.0 7782.6 7886.0 7919.0 7940.0 9104.2 10591.4 10922.5 11342.1 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5482.71 Median:5246.80 stddev:3016.87 (57.5%) stddev-sans-first:3010.21 07:29:16 INFO - PROCESS | 1649 | Values: 1463.3 1506.7 1598.8 1693.5 1747.9 7584.4 7851.1 8183.9 8622.1 8670.4 8105.4 8376.4 8413.1 8510.4 8522.4 2316.2 2343.5 2354.2 2360.5 2361.5 2347.1 2381.0 2402.0 2402.1 2407.4 1994.2 2319.7 2322.1 2342.2 2368.0 2052.9 2257.7 2301.7 2317.4 2350.3 2366.2 2380.9 2383.6 2387.5 2388.3 8463.1 8464.6 8552.1 8573.1 8624.6 5137.1 5303.4 5308.9 5310.3 5317.1 3351.9 3470.9 3674.8 3694.3 3700.4 1022.5 1060.0 1065.2 1110.6 1111.3 11050.3 11075.6 11169.7 11244.9 11251.4 4535.8 4685.7 4743.7 4773.1 4820.1 9349.8 9458.8 9471.6 9494.2 9507.0 5236.5 5257.2 5267.2 5270.2 5281.5 4325.2 4356.9 4788.6 4838.0 4842.9 3315.8 3810.1 3870.9 3910.9 3975.1 1081.4 1253.4 1275.1 1277.4 1288.7 754.8 795.4 886.9 904.0 922.0 5636.7 5660.3 5748.1 5808.9 5945.0 761.4 882.0 918.4 938.1 942.4 3177.5 3250.9 3837.5 3915.2 3933.7 10016.3 10475.5 10482.2 10494.4 10519.2 8519.9 10184.0 10299.9 10358.4 10403.8 7804.7 7820.3 7951.0 7989.8 8021.8 5492.5 5542.2 5604.2 5629.1 5737.1 7946.8 9516.4 9627.2 9630.8 9643.8 8901.5 9120.2 9757.5 9766.2 9901.5 5959.1 6246.7 6292.2 6789.2 6888.3 6868.6 7781.0 8054.4 8088.0 8108.9 7003.6 7105.3 7124.5 7150.2 7244.0 7068.9 7094.5 7098.1 7108.8 7204.4 6731.4 6778.9 6805.7 6822.4 6845.4 4887.2 4890.4 4895.3 4912.4 4965.6 4880.5 4920.7 4952.8 4981.6 5033.4 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4487.22 Median:5109.74 stddev:1403.71 (27.5%) stddev-sans-first:1372.02 07:29:16 INFO - PROCESS | 1649 | Values: 300.7 310.3 312.2 315.8 317.3 4975.0 5231.7 5347.3 5373.7 5522.6 4317.4 5119.2 5309.9 5336.7 5341.9 3710.1 3917.2 4083.5 4109.7 4113.8 3409.4 3472.8 4016.1 4097.2 4113.4 3977.6 4001.6 4023.0 4034.8 4082.3 3504.0 3952.1 4030.2 4051.5 4096.8 3551.4 3552.8 3562.4 3566.8 3583.4 4279.5 4919.0 5142.0 5147.7 5160.2 3864.7 3974.9 3991.0 4040.5 4623.2 5561.5 5637.8 5647.5 5647.9 5701.5 3190.4 3424.9 3588.5 3852.7 3928.4 5859.7 5862.0 5901.9 5956.3 5964.3 3606.4 3769.4 3907.5 3936.3 3939.4 3818.8 3941.1 3977.1 3979.1 3986.2 3772.6 4517.0 4552.7 4611.8 4626.3 5202.9 5662.0 5666.0 5687.7 5700.9 1055.7 1147.2 1200.9 1211.8 1276.4 803.7 804.7 852.6 876.7 878.5 5107.8 5109.8 5132.2 5151.9 5153.7 4677.0 5133.4 5162.5 5495.4 5600.5 4676.0 4692.8 4794.5 5036.8 5109.6 2661.1 2768.6 2769.6 2873.8 2897.5 5023.4 5288.6 5332.7 5615.7 5673.0 4520.6 5182.7 5242.7 5658.9 5685.8 5394.5 5429.0 5658.7 5662.0 5705.3 5453.7 5465.0 5837.7 5918.9 5937.5 4972.9 5083.0 5844.5 5866.5 5867.5 4959.6 5754.5 5968.0 6060.2 6090.8 3110.4 3836.6 3878.8 3925.4 3980.8 4759.8 5616.5 5861.9 5876.7 5900.7 5558.7 5594.8 5627.8 5723.7 5724.5 4299.8 4366.5 5369.8 5626.8 5699.9 5297.9 5340.0 5445.1 5596.9 5622.6 4818.4 5291.2 5650.1 5673.8 5772.9 4731.6 5316.2 5541.4 5608.9 5611.8 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19835.63 Median:514.31 stddev:28790.16 (5597.9%) stddev-sans-first:28834.83 07:29:16 INFO - PROCESS | 1649 | Values: 636.8 678.0 719.2 764.3 784.3 13755.8 14444.6 14487.6 14499.1 14656.9 12928.2 13010.3 13047.3 14276.0 14852.9 652.5 673.9 799.6 814.7 826.6 685.9 819.7 826.9 827.0 827.1 837.1 868.0 869.2 870.4 875.6 857.3 861.2 861.2 862.4 862.6 635.1 635.1 637.3 637.4 637.9 13958.4 14004.9 14200.3 14216.6 14252.8 11109.9 13344.7 13534.3 13659.6 13763.2 11065.7 11416.9 11436.5 11460.2 11927.3 3517.4 3620.2 3677.6 3681.9 3703.4 93448.5 94201.0 95554.6 95808.5 96549.5 10165.9 10217.9 10257.2 10291.2 10319.9 20080.5 20122.3 20279.9 20335.9 20813.8 7544.0 7582.1 7594.3 7634.9 7754.0 3480.4 3570.3 4122.4 4236.4 4250.0 3360.1 3471.5 3493.2 3591.0 3769.8 675.5 694.1 695.6 696.8 706.9 559.6 581.1 628.2 651.4 669.1 9078.6 9201.0 9223.4 9399.5 9456.6 511.7 516.9 568.5 625.1 694.2 4189.0 4335.9 4440.1 4470.4 4538.8 72566.6 77882.0 110279.8 111673.0 111837.4 62562.7 68264.0 70845.6 90540.0 94927.0 75892.3 84522.0 85050.0 88472.0 89157.0 6804.9 8559.9 8866.9 8926.1 9111.0 43922.6 55870.4 57688.4 58146.0 58168.5 56099.7 81039.0 81731.6 82617.0 82667.2 9124.5 9752.3 10734.9 10810.5 11004.6 12125.5 12288.7 12329.4 12494.0 12702.9 12593.1 12713.2 12861.9 13488.0 14029.7 11856.9 12940.2 13745.3 13837.9 14132.0 12444.9 12818.7 13234.0 13254.1 13698.5 9924.3 10823.0 11217.1 11221.1 11549.7 9614.1 10109.9 11137.7 11314.0 11430.9 07:29:16 INFO - PROCESS | 1649 | -------- Summary: end -------- 07:29:16 INFO - PROCESS | 1649 | 07:29:16 INFO - TEST-INFO | 1649: exit 0 07:29:16 INFO - TEST-OK | dromaeo_css | took 1116339ms 07:29:16 INFO - TEST-START | kraken 07:29:16 INFO - operating with platform_type : mac_ 07:29:16 INFO - Initialising browser for kraken test... 07:29:16 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpfZASOU/profile http://localhost:49242/getInfo.html) 07:29:18 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:29:19 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 07:29:19 INFO - PROCESS | 1656 | colorDepth:24 07:29:19 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 07:29:19 INFO - PROCESS | 1656 | __metrics 07:29:19 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 07:29:20 INFO - TEST-INFO | 1656: exit 0 07:29:20 INFO - Browser initialized. 07:29:20 INFO - Running cycle 1/1 for kraken test... 07:29:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpfZASOU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:29:32 INFO - PROCESS | 1658 | RSS: Main: 224256000 07:29:32 INFO - PROCESS | 1658 | 07:29:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 07:29:36 INFO - PROCESS | 1658 | RSS: Main: 236871680 07:29:36 INFO - PROCESS | 1658 | 07:29:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 07:29:39 INFO - PROCESS | 1658 | RSS: Main: 244355072 07:29:39 INFO - PROCESS | 1658 | 07:29:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 07:29:43 INFO - PROCESS | 1658 | RSS: Main: 248180736 07:29:43 INFO - PROCESS | 1658 | 07:29:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 07:29:46 INFO - PROCESS | 1658 | RSS: Main: 249692160 07:29:46 INFO - PROCESS | 1658 | 07:29:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:29:49 INFO - PROCESS | 1658 | RSS: Main: 249888768 07:29:49 INFO - PROCESS | 1658 | 07:29:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 07:29:52 INFO - PROCESS | 1658 | RSS: Main: 253501440 07:29:52 INFO - PROCESS | 1658 | 07:29:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 07:29:56 INFO - PROCESS | 1658 | RSS: Main: 252567552 07:29:56 INFO - PROCESS | 1658 | 07:29:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 07:29:59 INFO - PROCESS | 1658 | RSS: Main: 252829696 07:29:59 INFO - PROCESS | 1658 | 07:30:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:30:02 INFO - PROCESS | 1658 | RSS: Main: 251965440 07:30:02 INFO - PROCESS | 1658 | 07:30:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:30:04 INFO - PROCESS | 1658 | RSS: Main: 251981824 07:30:04 INFO - PROCESS | 1658 | 07:30:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:30:07 INFO - PROCESS | 1658 | RSS: Main: 252071936 07:30:07 INFO - PROCESS | 1658 | 07:30:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:30:11 INFO - PROCESS | 1658 | RSS: Main: 252391424 07:30:11 INFO - PROCESS | 1658 | 07:30:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:30:15 INFO - PROCESS | 1658 | RSS: Main: 252297216 07:30:15 INFO - PROCESS | 1658 | 07:30:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 07:30:17 INFO - PROCESS | 1658 | RSS: Main: 254226432 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | __start_tp_report 07:30:17 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 07:30:17 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 07:30:17 INFO - PROCESS | 1658 | |i|pagename|runs| 07:30:17 INFO - PROCESS | 1658 | |0;ai-astar;104;118;119;121;125;174;170;119;117;167 07:30:17 INFO - PROCESS | 1658 | |1;audio-beat-detection;154;138;150;150;116;126;149;149;145;152 07:30:17 INFO - PROCESS | 1658 | |2;audio-dft;196;190;196;198;198;197;196;200;233;202 07:30:17 INFO - PROCESS | 1658 | |3;audio-fft;82;61;70;87;66;86;85;89;70;51 07:30:17 INFO - PROCESS | 1658 | |4;audio-oscillator;88;67;91;73;69;72;85;72;71;90 07:30:17 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;108;112;134;109;87;93;91;92;136;86 07:30:17 INFO - PROCESS | 1658 | |6;imaging-darkroom;95;93;138;152;95;94;102;96;97;156 07:30:17 INFO - PROCESS | 1658 | |7;imaging-desaturate;87;88;87;123;78;134;145;129;88;99 07:30:17 INFO - PROCESS | 1658 | |8;json-parse-financial;55;56;56;55;60;52;57;91;88;50 07:30:17 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;69;73;68;94;115;111;84;73;70;71 07:30:17 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;87;86;85;88;87;83;85;87;86;91 07:30:17 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;156;173;147;145;148;144;149;159;210;160 07:30:17 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;173;174;179;157;166;232;238;213;172;210 07:30:17 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;65;65;66;66;68;78;66;68;68;65 07:30:17 INFO - PROCESS | 1658 | __end_tp_report 07:30:17 INFO - PROCESS | 1658 | __start_cc_report 07:30:17 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1028 07:30:17 INFO - PROCESS | 1658 | __end_cc_report 07:30:17 INFO - PROCESS | 1658 | __startTimestamp1479569417199__endTimestamp 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | ------- Summary: start ------- 07:30:17 INFO - PROCESS | 1658 | Number of tests: 14 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:133.40 Median:123.00 stddev:26.09 (21.2%) stddev-sans-first:25.41 07:30:17 INFO - PROCESS | 1658 | Values: 104.0 118.0 119.0 121.0 125.0 174.0 170.0 119.0 117.0 167.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:149.50 stddev:12.56 (8.4%) stddev-sans-first:12.66 07:30:17 INFO - PROCESS | 1658 | Values: 154.0 138.0 150.0 150.0 116.0 126.0 149.0 149.0 145.0 152.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:200.60 Median:198.00 stddev:11.81 (6.0%) stddev-sans-first:12.40 07:30:17 INFO - PROCESS | 1658 | Values: 196.0 190.0 196.0 198.0 198.0 197.0 196.0 200.0 233.0 202.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:74.70 Median:83.50 stddev:12.96 (15.5%) stddev-sans-first:13.48 07:30:17 INFO - PROCESS | 1658 | Values: 82.0 61.0 70.0 87.0 66.0 86.0 85.0 89.0 70.0 51.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:9.48 (12.0%) stddev-sans-first:9.31 07:30:17 INFO - PROCESS | 1658 | Values: 88.0 67.0 91.0 73.0 69.0 72.0 85.0 72.0 71.0 90.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:104.80 Median:86.50 stddev:18.47 (21.4%) stddev-sans-first:19.55 07:30:17 INFO - PROCESS | 1658 | Values: 108.0 112.0 134.0 109.0 87.0 93.0 91.0 92.0 136.0 86.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:94.50 stddev:25.94 (27.4%) stddev-sans-first:26.79 07:30:17 INFO - PROCESS | 1658 | Values: 95.0 93.0 138.0 152.0 95.0 94.0 102.0 96.0 97.0 156.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:87.00 stddev:24.33 (28.0%) stddev-sans-first:24.83 07:30:17 INFO - PROCESS | 1658 | Values: 87.0 88.0 87.0 123.0 78.0 134.0 145.0 129.0 88.0 99.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:56.50 stddev:14.76 (26.1%) stddev-sans-first:15.43 07:30:17 INFO - PROCESS | 1658 | Values: 55.0 56.0 56.0 55.0 60.0 52.0 57.0 91.0 88.0 50.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:72.00 stddev:17.84 (24.8%) stddev-sans-first:18.21 07:30:17 INFO - PROCESS | 1658 | Values: 69.0 73.0 68.0 94.0 115.0 111.0 84.0 73.0 70.0 71.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.00 stddev:2.12 (2.4%) stddev-sans-first:2.24 07:30:17 INFO - PROCESS | 1658 | Values: 87.0 86.0 85.0 88.0 87.0 83.0 85.0 87.0 86.0 91.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:159.10 Median:157.50 stddev:19.98 (12.7%) stddev-sans-first:21.16 07:30:17 INFO - PROCESS | 1658 | Values: 156.0 173.0 147.0 145.0 148.0 144.0 149.0 159.0 210.0 160.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.40 Median:194.50 stddev:29.12 (15.0%) stddev-sans-first:30.12 07:30:17 INFO - PROCESS | 1658 | Values: 173.0 174.0 179.0 157.0 166.0 232.0 238.0 213.0 172.0 210.0 07:30:17 INFO - PROCESS | 1658 | 07:30:17 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:3.89 (5.8%) stddev-sans-first:4.02 07:30:17 INFO - PROCESS | 1658 | Values: 65.0 65.0 66.0 66.0 68.0 78.0 66.0 68.0 68.0 65.0 07:30:17 INFO - PROCESS | 1658 | -------- Summary: end -------- 07:30:17 INFO - PROCESS | 1658 | 07:30:18 INFO - TEST-INFO | 1658: exit 0 07:30:18 INFO - TEST-OK | kraken | took 61336ms 07:30:18 INFO - SUITE-END | took 1177s 07:30:18 INFO - Completed test suite (00:19:37) 07:30:18 INFO - javascript benchmark 07:30:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7107.2925630475465, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.629560748319, 3379.746519011074, 3403.4894765785143, 3403.948940765889, 3414.5731783527053, 5619.0, 5699.458551437617, 5721.227634269372, 5727.713614319277, 5779.479846813786, 5711.286089238845, 5724.7996320128805, 5749.3388260350075, 5770.624909380887, 5822.330432000321, 5227.32044834171, 5242.606804489659, 5261.710605916673, 5276.314079169702, 5291.735413229342, 5256.92114618281, 5290.65610735301, 5319.122344813101, 5322.946770532303, 5338.91991620126, 5161.0968080585935, 5198.532132108109, 5288.365396152467, 5303.761330740107, 5325.520703136717, 5192.1432963560965, 5206.661566998152, 5261.973690131525, 5279.313689220377, 5300.1519756838725, 5193.792248310063, 5210.192420174879, 5232.0, 5246.842594722164, 5336.653117547347, 5531.948929703343, 5756.079027355603, 5789.45000224978, 5838.708064596795, 5848.064309710426, 6029.457348838626, 6131.509479241695, 6139.829471381233, 6153.292371377304, 6180.845478863064, 6254.437100660963, 6272.968635156795, 6283.340249273851, 6326.114343991845, 6338.746450141989, 5845.123231515222, 5853.619514731528, 5870.26621672291, 5895.263092113486, 5905.46850783423, 6157.322694503601, 6177.4749146322165, 6190.226221722285, 6257.186565746515, 6268.153799237136, 5944.7027648617395, 5953.613015154001, 5977.282726072899, 5981.521478281814, 5983.461488466059, 6073.848153796191, 6116.724747386378, 6123.387661233841, 6148.1392605035335, 6158.846028849225, 6107.664078475637, 6108.949222962805, 6145.754169833157, 6173.753049878044, 6232.439080482778, 6162.198914141133, 6204.0, 6245.188125543741, 6250.374962503804, 6253.53098517613, 4895.926561101586, 4917.680350777188, 4921.8523444296725, 4935.457099719028, 4942.159832828428, 4341.696081274281, 4351.173277077409, 4354.651627869763, 4366.235908715962, 4373.169097871394, 4745.881352966204, 4785.353977213102, 5780.306363236438, 5791.102379131242, 5832.562557808182, 6163.9383606163365, 6255.186825712628, 6256.405641464054, 6260.24877014752, 6261.843453913689, 5785.392533783976, 5810.273715785527, 5865.384134665884, 5896.93855106085, 6009.729562169713, 5398.055340315507, 5411.675299481965, 5411.675299482044, 5423.376311724202, 5486.807961721401, 5553.361363443256, 6289.7484100635465, 6303.1175635412, 6308.747650093945, 6316.684165791692, 6112.685862051923, 6156.168917196301, 6183.659898705434, 6207.96896015517, 6225.3774622537385, 6282.3089460158435, 6288.654124023223, 6305.684715764101, 6313.747450101944, 6339.049142628643, 6239.438450539473, 6250.499960003098, 6266.0, 6266.905996410149, 6285.622862628257, 6094.329623741473, 6142.007694998287, 6167.105769663463, 6189.0097584386285, 6310.936890631035, 4529.754317562565, 5997.500624843789, 6045.667488288011, 6131.223750760645, 6143.542582966849, 6118.969405152946, 6120.724567394389, 6130.601510901955, 6151.5386346025125, 6151.984922487918, 5715.085586306171, 5750.022496175577, 5856.150858125633, 5857.0, 5989.700514974321, 6026.487748541248, 6066.7269972850445, 6079.544034197364, 6099.8780024398375, 6136.549713441251, 5433.695912980935, 6005.249343832021, 6011.098335249748, 6019.9465093609315, 6028.547858910511], "value": 5684.99432806766, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.57674228081305, 518.823599976008, 526.6918852471302, 539.8812261302512, 543.2096299883669, 30282.394352112973, 39076.609233907686, 40315.59684403155, 40521.1895762085, 42849.0, 32687.67312326876, 37695.811520942356, 38864.02839929003, 41844.372334415006, 42407.36388954161, 8094.392920530954, 9277.675281365153, 9291.163795258426, 9447.952760236189, 9458.101480359355, 9405.529723513831, 9469.763255918588, 9479.620815167385, 9496.335256532046, 9532.618695252217, 9440.905590944105, 9464.574094165779, 9548.713538593853, 9552.80894382112, 9559.13967742903, 9451.0, 9491.19324857388, 9510.001449847805, 9511.286653501014, 9569.473678947656, 9343.065693430603, 9384.483853388061, 9412.341136120474, 9413.57638906251, 9425.0, 39695.60304396948, 39920.401193982114, 39925.20149597021, 39993.80003099995, 40132.996675083064, 2885.5094633912286, 3302.2204070248054, 3666.724995625312, 3803.5245594300713, 3940.7241493095494, 29010.0, 29092.836286548725, 29144.854275728696, 29685.9609913652, 29922.653480593424, 3084.3214492811544, 3134.200778801414, 3159.368126374711, 3267.852946617384, 3305.4876494143446, 496571.0, 498232.0, 505128.0, 506020.0, 507600.99999999633, 53411.46588534065, 60296.69851650801, 60511.78976420447, 60611.69694151501, 60721.0, 51532.484675152766, 62290.68854655698, 62516.0, 63097.36902630915, 63115.68442157852, 15761.921190394203, 16087.0, 16275.593110172103, 16351.100689461955, 16362.0, 12543.0, 12653.493860245488, 12677.619671409873, 12687.429065692066, 12737.745245095046, 16316.75524867128, 16521.66956660884, 16640.83359166417, 16661.583460413585, 16699.91650041742, 3119.7348225401097, 5782.88434231313, 5998.880022399528, 6016.548758843104, 6086.482648974798, 864.7449002544139, 1000.6997900629782, 1046.7016900183412, 1051.8842927277992, 1071.8660167479065, 30036.849815750782, 30119.0, 30164.54753178704, 30530.84734576313, 30631.69368306333, 857.6012154348133, 954.8185844689486, 1018.1701912940929, 1054.6941386997837, 1108.778244351133, 14869.330880110421, 15286.464973725866, 15354.155521446428, 15417.614559636098, 15518.69274572914, 665735.0, 667736.0, 670296.0, 670355.0, 675869.0, 55953.16070258949, 57653.42346576564, 58033.4196658028, 58050.709746451, 58288.41711582915, 1328.4154971812372, 1343.7379710956466, 1361.6255529729206, 1366.3031853754658, 1366.4261010375667, 25625.10312139066, 29158.0, 29639.703602963695, 29929.0, 30217.244568885075, 35953.64046359608, 40960.59039409687, 49887.251691225385, 50748.0, 50855.0, 556769.0, 634708.4793728191, 637454.0, 641253.0, 644103.0, 25808.96764129489, 26099.347516312246, 26217.344566385993, 26385.60421593716, 26637.801298941205, 45128.774356129325, 45969.31046034245, 46174.92212850497, 46184.53815461937, 46376.536234638574, 38154.236915262096, 38354.0, 38934.61065389423, 39428.2114357717, 39680.60319396769, 37967.430488542144, 38396.0, 39142.412863806494, 39200.29913997258, 40049.79975100106, 31213.8439307802, 37237.255254895295, 37239.0, 37525.6247437533, 37725.81137094297, 32827.67172328342, 34149.65850341465, 34190.80332188361, 34664.30671386508, 34980.82509587436, 25769.0, 25805.870970645028, 25897.482050359264, 25952.0, 26302.079427219956], "value": 19527.60665906156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.9121306492974, 376.394005650902, 389.9512560929884, 394.57977254224267, 405.17546792277705, 17790.82209177908, 18905.0, 19626.018699065044, 19766.11052502637, 19814.504637384074, 18634.627307453844, 18920.621587568276, 19018.049097545136, 19159.61680766387, 19553.924534150618, 5351.812686555972, 5415.943890941268, 5480.835574932748, 5758.481736643701, 5771.422857714237, 5558.833235002957, 5721.170430287606, 5723.141528770676, 5735.967525845346, 5750.568707346944, 5461.972690136544, 5675.432456754313, 5688.687122208277, 5722.113072473773, 5773.855653608652, 5772.393898640625, 5823.184754134425, 5873.500752436048, 5874.970625146847, 5897.115432685088, 5516.282883225155, 5680.204771332017, 5690.118031705092, 5717.228174196472, 5719.914201286984, 12444.253344799341, 12686.619401417973, 12743.107982441234, 12994.090413671047, 13060.542880999119, 2243.14760391052, 2305.5734689082574, 2410.819188560865, 2602.557565213918, 2825.3642930340716, 9902.801943961153, 10106.595736170619, 10117.797644047077, 10295.79408411835, 14864.108153510824, 1584.6830633873271, 1762.189392879277, 1799.1723807048643, 1806.692862213427, 1823.216017112641, 109103.45448272787, 114619.0, 120559.0, 121286.34916191346, 122202.35040925432, 9557.952210238904, 10928.672139835659, 11181.608643697595, 11315.603953861575, 11535.0, 14188.0, 14303.64240893986, 14310.141391516543, 14323.641908952359, 14329.85670143306, 7056.0, 8347.540885251248, 8433.19884610961, 8468.957655211685, 8578.399512034222, 5931.525477961753, 6465.321141280192, 7761.573113478813, 8097.757067287991, 8362.623681934327, 4293.463317085364, 4454.7549884756645, 4460.241758900995, 4531.13908357411, 4535.7732113394195, 1599.6882556303951, 1867.3930972434014, 1913.521989024217, 1920.9135588898253, 1923.3364489251187, 867.7223288547605, 896.8744375787395, 921.1433366968649, 940.4310392212612, 992.1120597065586, 19710.802891971183, 20360.490987725425, 20412.0, 20591.8970405147, 21262.362129136152, 909.6497848328441, 929.3494553812359, 942.962281508732, 959.5729900194347, 998.4259051045533, 10249.538770755335, 10716.571337146583, 10743.301685390425, 10881.673549793519, 10887.0, 586138.0, 592704.0, 599208.0, 601019.0, 602275.0, 120429.39785301017, 122759.0, 125714.0, 129586.0, 131060.34469827781, 873.2490058549643, 918.1461241045891, 933.5239028095721, 936.5317341329335, 938.1134827588, 17681.469555913343, 17708.0, 18010.189541470405, 18083.54791130232, 18182.181801818686, 44446.55553444424, 48904.35408636174, 52057.21914171215, 52246.73876630593, 52699.94600108053, 73124.63437682777, 74886.62556687182, 77714.0, 80359.19640803518, 80671.0, 6656.301088385843, 7566.735164269224, 7578.393728501817, 7636.160022397421, 7723.0, 11069.391183484664, 11953.402329883646, 12010.519579217083, 12092.0, 12335.568255111028, 10091.091801737879, 10351.741206469898, 10361.792764144524, 10419.739506512398, 10668.62659806934, 9232.538373081454, 9653.276004299565, 10202.438865862448, 10413.531391087268, 10492.52783624754, 10122.291439599157, 10224.591016359262, 10795.6221532246, 11008.559657613927, 11042.503087360927, 7654.387648987956, 7766.961165194138, 7782.571958542108, 7885.974823272938, 7919.049714034353, 7939.960300198462, 9104.18062374363, 10591.417472039111, 10922.453877305945, 11342.092632589538], "value": 9094.603653857033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.3195564062712, 1506.7137243923655, 1598.7529726813082, 1693.5173475559468, 1747.9475615731533, 7584.355329796974, 7851.097123830761, 8183.91816081839, 8622.09468005859, 8670.393072484929, 8105.392095592824, 8376.3717721171, 8413.074561798197, 8510.404271700983, 8522.446041007346, 2316.200910685815, 2343.4727186383097, 2354.1878052072093, 2360.5042940982416, 2361.53949979754, 2347.14329269817, 2380.988095059531, 2401.9639705404347, 2402.087206861399, 2407.38611654028, 1994.212286146971, 2319.7100362454694, 2322.094383190557, 2342.156823543523, 2367.9763202368013, 2052.938411847647, 2257.656694266917, 2301.6547517872286, 2317.409060689522, 2350.3066595354326, 2366.1600131953082, 2380.8690522021284, 2383.5590415772963, 2387.5344307859973, 2388.3432056184515, 8463.069062403192, 8464.619092140869, 8552.102029286898, 8573.099824518398, 8624.568771561397, 5137.12668846297, 5303.443138470444, 5308.946910530923, 5310.309659744247, 5317.122674758699, 3351.916202094967, 3470.9066644007307, 3674.779513229215, 3694.261147770457, 3700.4264339027495, 1022.4580972084807, 1059.978800423995, 1065.248999455382, 1110.5835311758092, 1111.261011427408, 11050.281731687415, 11075.612353567587, 11169.664910052712, 11244.887551124548, 11251.43742812856, 4535.841245556389, 4685.718856868531, 4743.739094349775, 4773.11697335994, 4820.060088282821, 9349.766255843522, 9458.763530911645, 9471.573779179953, 9494.240460763122, 9507.0, 5236.450172731808, 5257.158854583248, 5267.183586544092, 5270.183121616155, 5281.498257665592, 4325.2214601371425, 4356.912861742748, 4788.569028787426, 4837.975810120997, 4842.903141937141, 3315.8063097284958, 3810.0855794609092, 3870.903227419281, 3910.9413358799643, 3975.0857302820514, 1081.351189286422, 1253.404632799413, 1275.0819410024767, 1277.3549357574366, 1288.7229245712217, 754.8150703077781, 795.4471642208725, 886.9423487473293, 903.9683611073581, 921.9585118669676, 5636.689982050944, 5660.29246344207, 5748.080307150836, 5808.867270882222, 5945.048792193228, 761.3604572159302, 881.9823603527893, 918.3846822628722, 938.0806809326898, 942.3733217410345, 3177.459831828595, 3250.902472925816, 3837.5394952605866, 3915.169670578181, 3933.7049721270446, 10016.348937319051, 10475.528601212964, 10482.21383396233, 10494.422806745702, 10519.211059170593, 8519.872201916976, 10184.0, 10299.897001030044, 10358.430286334324, 10403.84394234087, 7804.726834560764, 7820.335271501986, 7951.0, 7989.800254993555, 8021.8395632088195, 5492.47821456961, 5542.168674698827, 5604.159376093456, 5629.099344104923, 5737.08206686928, 7946.801329966797, 9516.38143520669, 9627.181689556466, 9630.759231019281, 9643.807123857623, 8901.51041692713, 9120.224780893699, 9757.463339516395, 9766.21870250364, 9901.504924753877, 5959.135925290895, 6246.719422518464, 6292.150559674568, 6789.1852977643, 6888.311168883071, 6868.587884726932, 7781.0, 8054.395920305883, 8087.959560202161, 8108.918910811052, 7003.5797852129035, 7105.32499412555, 7124.465665075037, 7150.213476517475, 7243.963780181276, 7068.893966590608, 7094.4679149062995, 7098.112735908012, 7108.786736397917, 7204.423646108194, 6731.427828634621, 6778.8983165251575, 6805.6937437816405, 6822.420094291842, 6845.418139458202, 4887.242477416006, 4890.364252647133, 4895.314655948099, 4912.410510738591, 4965.553100220853, 4880.511948805091, 4920.6555541111775, 4952.7771250294545, 4981.576565991786, 5033.370828646419], "value": 4436.976714617155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7413624283116, 310.3173019357414, 312.2490410562597, 315.8231390421364, 317.303518776286, 4974.950250497498, 5231.659942103761, 5347.25138480613, 5373.731313434328, 5522.613417060812, 4317.373980772794, 5119.1809310510325, 5309.8938021239555, 5336.67979921205, 5341.866453338668, 3710.12811989183, 3917.1773927475265, 4083.4895638045246, 4109.732867363627, 4113.773742444165, 3409.3863104641155, 3472.8437220325145, 4016.096378314885, 4097.201045796071, 4113.444684967521, 3977.5624681285035, 4001.5798341174195, 4022.9597704022876, 4034.7780872052026, 4082.265192265391, 3503.964960350415, 3952.130531283113, 4030.193961207741, 4051.534073581546, 4096.774677392742, 3551.449525323579, 3552.8223588820456, 3562.447820587813, 3566.7859928404378, 3583.372909740785, 4279.486461624594, 4918.9754051229875, 5141.974290128563, 5147.679746563483, 5160.200168973815, 3864.7101467389775, 3974.9006274843073, 3990.980045099785, 4040.4747382840337, 4623.190941585242, 5561.5272701820395, 5637.830865074055, 5647.548196144298, 5647.943520564824, 5701.543876489871, 3190.4416727072903, 3424.9486257706153, 3588.5334906462253, 3852.7110466715108, 3928.3893603622664, 5859.707014649293, 5861.970690146564, 5901.911471327933, 5956.255468066492, 5964.284285885721, 3606.422972324468, 3769.4345848123003, 3907.4529565860803, 3936.3111455495173, 3939.3697008478507, 3818.7708737475286, 3941.0935484838074, 3977.125032492847, 3979.144483935968, 3986.1828325193264, 3772.603876592973, 4516.977415112903, 4552.658550608718, 4611.838585649486, 4626.306054091913, 5202.869928251824, 5661.971690141605, 5665.971670141623, 5687.7156142192725, 5700.945325114867, 1055.6885718712997, 1147.185498296219, 1200.903927685783, 1211.763706077324, 1276.3554405025402, 803.7381311341138, 804.7124600639048, 852.6120615120105, 876.7106854738049, 878.5343767803074, 5107.846764597068, 5109.846704598868, 5132.177302483619, 5151.948480515147, 5153.711572106973, 4676.95323046772, 5133.383993920754, 5162.483751624883, 5495.395506494362, 5600.467955544217, 4675.978658353813, 4692.846830554915, 4794.523970121634, 5036.7733451994745, 5109.642325037212, 2661.0973154194844, 2768.6123942648046, 2769.587510369726, 2873.827570345786, 2897.5219088850104, 5023.447420783711, 5288.550473209821, 5332.706701131475, 5615.747291371898, 5673.0, 4520.61574766142, 5182.714950677673, 5242.6592271503, 5658.943410565924, 5685.772569097273, 5394.487523685244, 5428.999999999921, 5658.660480371191, 5661.961693790232, 5705.258316418839, 5453.727313634382, 5465.0, 5837.737301821343, 5918.852028699144, 5937.524998000236, 4972.8756781080765, 5083.0, 5844.503217226414, 5866.50134738552, 5867.530597552271, 4959.553640172402, 5754.525554011815, 5968.015277479341, 6060.212172417734, 6090.847728806815, 3110.440120778191, 3836.582952739756, 3878.789090218661, 3925.391564307537, 3980.7611543307494, 4759.833405830917, 5616.494515493625, 5861.9413805863105, 5876.706164691663, 5900.7049647518315, 5558.749856256559, 5594.812495377073, 5627.839632532762, 5723.656580605177, 5724.542036637143, 4299.763513006815, 4366.476022877275, 5369.812056578001, 5626.774929002958, 5699.857503562444, 5297.867553311198, 5339.97081109176, 5445.074337362737, 5596.8937239832285, 5622.6345287557815, 4818.386579634686, 5291.206319052173, 5650.0677388232125, 5673.773039085908, 5772.855678608068, 4731.6214702823, 5316.208990280134, 5541.418151094077, 5608.934302482516, 5611.80358687444], "value": 4035.118807254747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.8409494719613, 677.9966100169498, 719.1980941250507, 764.2586690909816, 784.3019712455913, 13755.79366309506, 14444.5666630001, 14487.637809054779, 14499.057561258538, 14656.853431465683, 12928.224306541591, 13010.284434356106, 13047.282399468026, 14276.0, 14852.851471485283, 652.5301782716449, 673.8753330633824, 799.6201804143014, 814.7474282972297, 826.6404114210308, 685.8559702462464, 819.7008092046419, 826.9421140520166, 827.0117209934101, 827.1149869639488, 837.0792128658488, 868.0061329777401, 869.2394155114275, 870.3689824876977, 875.5840975536632, 857.3398483167925, 861.2076889261895, 861.2119910282115, 862.4351050062219, 862.633380813155, 635.0601110356652, 635.1013316157631, 637.2544123375661, 637.4103953842723, 637.9138816259792, 13958.371873265727, 14004.859951400458, 14200.289985500684, 14216.573502794932, 14252.786208206886, 11109.944450277697, 13344.666383340495, 13534.323283835769, 13659.618262542685, 13763.174209547458, 11065.668029959113, 11416.885831141748, 11436.542538298458, 11460.197786154973, 11927.343996080212, 3517.384457719889, 3620.239749652576, 3677.595464498903, 3681.907952301187, 3703.407454807245, 93448.53275733646, 94201.0, 95554.56668149984, 95808.52095739616, 96549.5172524133, 10165.94917025425, 10217.948910255402, 10257.230707696952, 10291.228157888188, 10319.948400258101, 20080.49798755043, 20122.295719650036, 20279.898600506902, 20335.898320508597, 20813.79186208149, 7543.9622801886735, 7582.128055273603, 7594.31651151388, 7634.885476717854, 7753.991981042428, 3480.4083305838067, 3570.285942811448, 4122.42286079949, 4236.364545318227, 4249.978750106292, 3360.1431634932937, 3471.531343268677, 3493.1965647900615, 3590.982045089758, 3769.7738135711943, 675.4767998164109, 694.1323345817727, 695.6104581434413, 696.8432102776835, 706.8939659051183, 559.636236446305, 581.1486172756931, 628.1833616298793, 651.4299987510927, 669.1334721535623, 9078.636854525745, 9200.95399522998, 9223.44659320443, 9399.530023498934, 9456.57445414958, 511.71855479486135, 516.8914527949098, 568.5196009372074, 625.0811307378146, 694.2432748304271, 4188.979055104766, 4335.9349609755245, 4440.134173836135, 4470.396496472999, 4538.795754191069, 72566.63716681383, 77882.0, 110279.79440411147, 111673.0, 111837.44081279541, 62562.68718656468, 68263.97604035944, 70845.64577177081, 90540.0, 94927.0, 75892.34376796786, 84522.0, 85050.0, 88472.0, 89157.0, 6804.9319506804295, 8559.871601925852, 8866.86699699492, 8926.094709728346, 9111.043340449423, 43922.560774391844, 55870.44129558653, 57688.4231157683, 58146.0, 58168.54578635568, 56099.71950140223, 81039.0, 81731.59134204291, 82617.0, 82667.17332826594, 9124.543772811465, 9752.317337786288, 10734.86494414303, 10810.45947702596, 11004.614838480615, 12125.514979400725, 12288.692782680504, 12329.383530823603, 12494.0, 12702.872971270539, 12593.118481706559, 12713.237205767684, 12861.935690321488, 13488.0, 14029.719405611626, 11856.940715296369, 12940.223586584834, 13745.312734363042, 13837.861621383658, 14132.0, 12444.942179914446, 12818.67953301175, 13234.007449441138, 13254.072214944861, 13698.452061917811, 9924.305298629026, 10823.0, 11217.102631789601, 11221.102311814871, 11549.6535103947, 9614.086661767122, 10109.898901011189, 11137.721556960816, 11314.0, 11430.942845285721], "value": 7129.723499950344, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 119.0, 121.0, 125.0, 174.0, 170.0, 119.0, 117.0, 167.0], "value": 133.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 138.0, 150.0, 150.0, 116.0, 126.0, 149.0, 149.0, 145.0, 152.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 190.0, 196.0, 198.0, 198.0, 197.0, 196.0, 200.0, 233.0, 202.0], "value": 200.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 70.0, 87.0, 66.0, 86.0, 85.0, 89.0, 70.0, 51.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 67.0, 91.0, 73.0, 69.0, 72.0, 85.0, 72.0, 71.0, 90.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 134.0, 109.0, 87.0, 93.0, 91.0, 92.0, 136.0, 86.0], "value": 104.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 138.0, 152.0, 95.0, 94.0, 102.0, 96.0, 97.0, 156.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 123.0, 78.0, 134.0, 145.0, 129.0, 88.0, 99.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 60.0, 52.0, 57.0, 91.0, 88.0, 50.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 68.0, 94.0, 115.0, 111.0, 84.0, 73.0, 70.0, 71.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 88.0, 87.0, 83.0, 85.0, 87.0, 86.0, 91.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 173.0, 147.0, 145.0, 148.0, 144.0, 149.0, 159.0, 210.0, 160.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 179.0, 157.0, 166.0, 232.0, 238.0, 213.0, 172.0, 210.0], "value": 191.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 68.0, 78.0, 66.0, 68.0, 68.0, 65.0], "value": 67.5, "unit": "score"}]}]} 07:30:18 INFO - Return code: 0 07:30:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:30:18 ERROR - # TBPL SUCCESS # 07:30:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:30:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:30:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:30:18 INFO - 2016-11-19 07:30:18.513 screenresolution[1664:10896] starting screenresolution argv=screenresolution get 07:30:18 INFO - 2016-11-19 07:30:18.516 screenresolution[1664:10896] Display 0: 1600x1200x32@60 07:30:18 INFO - 2016-11-19 07:30:18.523 screenresolution[1665:10899] starting screenresolution argv=screenresolution list 07:30:18 INFO - Available Modes on Display 0 07:30:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:30:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:30:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:30:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:30:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:30:18 INFO - Intel Iris: 07:30:18 INFO - Chipset Model: Intel Iris 07:30:18 INFO - Type: GPU 07:30:18 INFO - Bus: Built-In 07:30:18 INFO - VRAM (Dynamic, Max): 1536 MB 07:30:18 INFO - Vendor: Intel (0x8086) 07:30:18 INFO - Device ID: 0x0a2e 07:30:18 INFO - Revision ID: 0x0009 07:30:18 INFO - Displays: 07:30:18 INFO - Display: 07:30:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:30:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:30:18 INFO - Display Serial Number: 1600x1200 60 07:30:18 INFO - Main Display: Yes 07:30:18 INFO - Mirror: Off 07:30:18 INFO - Online: Yes 07:30:18 INFO - Rotation: Supported 07:30:18 INFO - Return code: 0 07:30:18 INFO - Running post-action listener: _resource_record_post_action 07:30:18 INFO - [mozharness: 2016-11-19 15:30:18.759605Z] Finished run-tests step (success) 07:30:18 INFO - Running post-run listener: _resource_record_post_run 07:30:18 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32144384; Write bytes: 657873920; Read time: 196; Write time: 1902 07:30:18 INFO - TinderboxPrint: CPU usage
54.3% 07:30:18 INFO - TinderboxPrint: I/O read bytes / time
32,144,384 / 196 07:30:18 INFO - TinderboxPrint: I/O write bytes / time
657,873,920 / 1,902 07:30:18 INFO - TinderboxPrint: CPU idle
2,210.7 (45.7%) 07:30:18 INFO - TinderboxPrint: CPU system
977.4 (20.2%) 07:30:18 INFO - TinderboxPrint: CPU user
1,653.6 (34.2%) 07:30:18 INFO - TinderboxPrint: Swap in / out
381,140,992 / 0 07:30:18 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346401280; Write bytes: 346506752; Read time: 26683; Write time: 730 07:30:18 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31214592; Write bytes: 301344256; Read time: 184; Write time: 1143 07:30:19 INFO - Running post-run listener: _upload_blobber_files 07:30:19 INFO - Blob upload gear active. 07:30:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:30:19 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 07:30:19 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'] 07:30:19 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 07:30:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:20 INFO - (blobuploader) - INFO - Done attempting. 07:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:30:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:22 INFO - (blobuploader) - INFO - Done attempting. 07:30:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:30:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:24 INFO - (blobuploader) - INFO - Done attempting. 07:30:24 INFO - (blobuploader) - INFO - Iteration through files over. 07:30:24 INFO - Return code: 0 07:30:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:30:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:30:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11e61ddc662cb62fb9419a78ec5fa0a5a01dde0d45db90142e742e6d0f27049ad0567c7ca572b6cbf5401a6ee0ed29d888b3f82716cb7ad82ed2bd74128f198d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84a88c45a849f63ebedca97f1c59725aa2c0a2baa7926ea61f47635d08938dd380a296ff3446ba2c0031d2ef29b7842de96e6428c2bf81655f705f87476e268", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e3ba3286feaf04da4e9ce14262dd06fa6f45940b3c6055c201c7a980b072553ede46103be3bebb480301a9bc12d0baab30af5dbfe9e09c33a5b54f2f3820c98"} 07:30:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:30:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:30:24 INFO - Contents: 07:30:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11e61ddc662cb62fb9419a78ec5fa0a5a01dde0d45db90142e742e6d0f27049ad0567c7ca572b6cbf5401a6ee0ed29d888b3f82716cb7ad82ed2bd74128f198d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84a88c45a849f63ebedca97f1c59725aa2c0a2baa7926ea61f47635d08938dd380a296ff3446ba2c0031d2ef29b7842de96e6428c2bf81655f705f87476e268", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e3ba3286feaf04da4e9ce14262dd06fa6f45940b3c6055c201c7a980b072553ede46103be3bebb480301a9bc12d0baab30af5dbfe9e09c33a5b54f2f3820c98"} 07:30:24 INFO - Running post-run listener: copy_logs_to_upload_dir 07:30:24 INFO - Copying logs to upload dir... 07:30:24 INFO - mkdir: /builds/slave/test/build/upload/logs 07:30:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.247587 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-19 07:30:24.625062) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 07:30:24.629241) ========= 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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11e61ddc662cb62fb9419a78ec5fa0a5a01dde0d45db90142e742e6d0f27049ad0567c7ca572b6cbf5401a6ee0ed29d888b3f82716cb7ad82ed2bd74128f198d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84a88c45a849f63ebedca97f1c59725aa2c0a2baa7926ea61f47635d08938dd380a296ff3446ba2c0031d2ef29b7842de96e6428c2bf81655f705f87476e268", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e3ba3286feaf04da4e9ce14262dd06fa6f45940b3c6055c201c7a980b072553ede46103be3bebb480301a9bc12d0baab30af5dbfe9e09c33a5b54f2f3820c98"} build_url:https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011054 build_url: 'https://queue.taskcluster.net/v1/task/QSS_u403QEqoMpJX3wmM5g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11e61ddc662cb62fb9419a78ec5fa0a5a01dde0d45db90142e742e6d0f27049ad0567c7ca572b6cbf5401a6ee0ed29d888b3f82716cb7ad82ed2bd74128f198d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e84a88c45a849f63ebedca97f1c59725aa2c0a2baa7926ea61f47635d08938dd380a296ff3446ba2c0031d2ef29b7842de96e6428c2bf81655f705f87476e268", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e3ba3286feaf04da4e9ce14262dd06fa6f45940b3c6055c201c7a980b072553ede46103be3bebb480301a9bc12d0baab30af5dbfe9e09c33a5b54f2f3820c98"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 07:30:24.662795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:30:24.663130) ========= 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.xH9fOLfT5r/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.hb6oTgO3Cf/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 07:30:24.711669) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 07:30:24.712018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 07:30:24.712498) ========= ========= Total master_lag: 0.18 =========