builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0243 starttime: 1482920032.01 results: success (0) buildid: 20161228004005 builduid: 3c7a96a68d674984b1b849b61ab6c31d revision: 2c316d8a97781a4c47d648bc87a00ff1d5155749 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.010329) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.010758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.011090) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.039738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.040392) ========= 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.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-12-28 02:13:52-- 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% 18.4M=0.001s 2016-12-28 02:13:52 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.455249 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.570208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.570585) ========= 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.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.025912 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:52.630488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 02:13:52.630926) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2c316d8a97781a4c47d648bc87a00ff1d5155749 --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 2c316d8a97781a4c47d648bc87a00ff1d5155749 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-12-28 02:13:52,741 truncating revision to first 12 chars 2016-12-28 02:13:52,741 Setting DEBUG logging. 2016-12-28 02:13:52,741 attempt 1/10 2016-12-28 02:13:52,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2c316d8a9778?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 02:13:53,862 unpacking tar archive at: mozilla-aurora-2c316d8a9778/testing/mozharness/ program finished with exit code 0 elapsedTime=1.533275 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 02:13:54.200600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:54.201338) ========= script_repo_revision: 2c316d8a97781a4c47d648bc87a00ff1d5155749 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:54.202252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:54.202964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 02:13:54.224549) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-28 02:13:54.225316) ========= /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.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:13:54 INFO - MultiFileLogger online at 20161228 02:13:54 in /builds/slave/test 02:13:54 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 02:13:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:13:54 INFO - {'append_to_log': False, 02:13:54 INFO - 'base_work_dir': '/builds/slave/test', 02:13:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:13:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:13:54 INFO - 'branch': 'Mozilla-Aurora', 02:13:54 INFO - 'buildbot_json_path': 'buildprops.json', 02:13:54 INFO - 'config_files': ('talos/mac_config.py',), 02:13:54 INFO - 'default_actions': ('clobber', 02:13:54 INFO - 'read-buildbot-config', 02:13:54 INFO - 'download-and-extract', 02:13:54 INFO - 'populate-webroot', 02:13:54 INFO - 'create-virtualenv', 02:13:54 INFO - 'install', 02:13:54 INFO - 'run-tests'), 02:13:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:13:54 INFO - 'download_minidump_stackwalk': True, 02:13:54 INFO - 'download_symbols': 'ondemand', 02:13:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:13:54 INFO - 'tooltool.py': '/tools/tooltool.py', 02:13:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:13:54 INFO - '/tools/misc-python/virtualenv.py')}, 02:13:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:13:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:13:54 INFO - 'installer_path': 'installer.exe', 02:13:54 INFO - 'log_level': 'info', 02:13:54 INFO - 'log_name': 'talos', 02:13:54 INFO - 'log_to_console': True, 02:13:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:13:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:13:54 INFO - 'opt_config_files': (), 02:13:54 INFO - 'pip_index': False, 02:13:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:13:54 INFO - 'cmd': ('bash', 02:13:54 INFO - '-c', 02:13:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:13:54 INFO - 'enabled': True, 02:13:54 INFO - 'halt_on_failure': False, 02:13:54 INFO - 'name': 'check_screen_resolution'},), 02:13:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:13:54 INFO - 'cmd': ('bash', 02:13:54 INFO - '-c', 02:13:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:13:54 INFO - 'enabled': True, 02:13:54 INFO - 'halt_on_failure': False, 02:13:54 INFO - 'name': 'check_screen_resolution'},), 02:13:54 INFO - 'run_cmd_checks_enabled': True, 02:13:54 INFO - 'sps_profile': False, 02:13:54 INFO - 'sps_profile_interval': 0, 02:13:54 INFO - 'suite': 'dromaeojs-e10s', 02:13:54 INFO - 'system_bits': '32', 02:13:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:13:54 INFO - 'title': 't-yosemite-r7-0243', 02:13:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:13:54 INFO - 'use_talos_json': True, 02:13:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:13:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:13:54 INFO - 'work_dir': 'build'} 02:13:54 INFO - [mozharness: 2016-12-28 10:13:54.435788Z] Running clobber step. 02:13:54 INFO - Running pre-action listener: _resource_record_pre_action 02:13:54 INFO - Running main action method: clobber 02:13:54 INFO - rmtree: /builds/slave/test/build 02:13:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:13:56 INFO - Running post-action listener: _resource_record_post_action 02:13:56 INFO - [mozharness: 2016-12-28 10:13:56.756595Z] Finished clobber step (success) 02:13:56 INFO - [mozharness: 2016-12-28 10:13:56.756722Z] Running read-buildbot-config step. 02:13:56 INFO - Running pre-action listener: _resource_record_pre_action 02:13:56 INFO - Running main action method: read_buildbot_config 02:13:56 INFO - Using buildbot properties: 02:13:56 INFO - { 02:13:56 INFO - "project": "", 02:13:56 INFO - "product": "firefox", 02:13:56 INFO - "script_repo_revision": "production", 02:13:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:13:56 INFO - "repository": "", 02:13:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:13:56 INFO - "buildid": "20161228004005", 02:13:56 INFO - "stage_platform": "macosx64", 02:13:56 INFO - "basedir": "/builds/slave/test", 02:13:56 INFO - "nightly_build": "True", 02:13:56 INFO - "buildnumber": 15, 02:13:56 INFO - "slavename": "t-yosemite-r7-0243", 02:13:56 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:13:56 INFO - "platform": "yosemite_r7", 02:13:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:13:56 INFO - "branch": "mozilla-aurora", 02:13:56 INFO - "repo_path": "releases/mozilla-aurora", 02:13:56 INFO - "revision": "2c316d8a97781a4c47d648bc87a00ff1d5155749", 02:13:56 INFO - "pgo_build": "False", 02:13:56 INFO - "builduid": "3c7a96a68d674984b1b849b61ab6c31d", 02:13:56 INFO - "slavebuilddir": "test" 02:13:56 INFO - } 02:13:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:13:56 INFO - Running post-action listener: _resource_record_post_action 02:13:56 INFO - [mozharness: 2016-12-28 10:13:56.760185Z] Finished read-buildbot-config step (success) 02:13:56 INFO - [mozharness: 2016-12-28 10:13:56.760300Z] Running download-and-extract step. 02:13:56 INFO - Running pre-action listener: _resource_record_pre_action 02:13:56 INFO - Running main action method: download_and_extract 02:13:56 INFO - mkdir: /builds/slave/test/build/tests 02:13:56 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')]} 02:13:56 INFO - trying https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:13:56 INFO - Downloading https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/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 02:13:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/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 02:13:57 INFO - Downloaded 1199 bytes. 02:13:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:13:57 INFO - Using the following test package requirements: 02:13:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:13:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:13:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:13:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'jsshell-mac.zip'], 02:13:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:13:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:13:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:13:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:13:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:13:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:13:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:13:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:13:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:13:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:13:57 INFO - Fetch https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:13:58 INFO - Http code: 200 02:13:58 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:13:58 INFO - x-amz-cf-id: QSad7EkV6gOjBbiH2K2CjQYwm1kCuEtmkooSNYt2oECigTyVFZ8csA== 02:13:58 INFO - x-amz-version-id: Rup00Ev5Db80z6WnNECq07aowBUT4TDf 02:14:01 INFO - Expected file size: 16268432 02:14:01 INFO - Obtained file size: 16268432 02:14:07 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 02:14:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:14:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:14:07 INFO - Fetch https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:14:08 INFO - Http code: 200 02:14:08 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 02:14:08 INFO - x-amz-cf-id: 5DgOAu_krJqmDv6eKR_DOVQl-HQEkiCbgFu4UaANuzcQHZF8PdpmYg== 02:14:08 INFO - x-amz-version-id: Rup00Ev5Db80z6WnNECq07aowBUT4TDf 02:14:08 INFO - Expected file size: 16268432 02:14:08 INFO - Obtained file size: 16268432 02:14:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:14:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:14:14 INFO - Fetch https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:14:15 INFO - Http code: 200 02:14:15 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 02:14:15 INFO - x-amz-cf-id: cy-2wDzMadD6YR6LhiKZ9enjOjpLKnXKhVkVw0NdCxx8YvwgZsOzIA== 02:14:15 INFO - x-amz-version-id: ncESHxphh.kuMWLO6wPsIFZe0C9IoVuc 02:14:15 INFO - Expected file size: 14409038 02:14:15 INFO - Obtained file size: 14409038 02:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:16 INFO - trying https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:14:16 INFO - Downloading https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:14:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/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 02:14:18 INFO - Downloaded 109951440 bytes. 02:14:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:14:18 INFO - mkdir: /builds/slave/test/properties 02:14:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:14:18 INFO - Writing to file /builds/slave/test/properties/build_url 02:14:18 INFO - Contents: 02:14:18 INFO - build_url:https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:14:19 INFO - Running post-action listener: _resource_record_post_action 02:14:19 INFO - Running post-action listener: set_extra_try_arguments 02:14:19 INFO - [mozharness: 2016-12-28 10:14:19.763248Z] Finished download-and-extract step (success) 02:14:19 INFO - [mozharness: 2016-12-28 10:14:19.763373Z] Running populate-webroot step. 02:14:19 INFO - Running pre-action listener: _resource_record_pre_action 02:14:19 INFO - Running main action method: populate_webroot 02:14:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:14:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:14:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:14:19 INFO - '--tppagecycles', 02:14:19 INFO - '7'], 02:14:19 INFO - 'tests': ['tsvgm']}}, 02:14:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:14:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:14:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:14:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:14:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:14:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:14:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['damp', 'tps']}, 02:14:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'tests': ['damp', 'tps']}, 02:14:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['dromaeo_dom']}, 02:14:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:14:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['basic_compositor_video']}, 02:14:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:14:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'other-e10s': {'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['a11yr', 02:14:19 INFO - 'ts_paint', 02:14:19 INFO - 'tpaint', 02:14:19 INFO - 'sessionrestore', 02:14:19 INFO - 'sessionrestore_no_auto_restore', 02:14:19 INFO - 'tabpaint']}, 02:14:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['tsvgx', 02:14:19 INFO - 'tsvgr_opacity', 02:14:19 INFO - 'tart', 02:14:19 INFO - 'tscrollx', 02:14:19 INFO - 'cart']}, 02:14:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:14:19 INFO - 'tsvgr_opacity', 02:14:19 INFO - 'tart', 02:14:19 INFO - 'tscrollx', 02:14:19 INFO - 'cart']}, 02:14:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'talos_options': ['--disable-e10s'], 02:14:19 INFO - 'tests': ['tp5o']}, 02:14:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'tests': ['tp5o']}, 02:14:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'talos_options': ['--disable-e10s', 02:14:19 INFO - '--xperf_path', 02:14:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:14:19 INFO - 'tests': ['tp5n']}, 02:14:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:14:19 INFO - 'talos_options': ['--xperf_path', 02:14:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:14:19 INFO - 'tests': ['tp5n']}}, 02:14:19 INFO - 'talos.zip': {'path': '', 02:14:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:14:19 INFO - Running post-action listener: _resource_record_post_action 02:14:19 INFO - [mozharness: 2016-12-28 10:14:19.781647Z] Finished populate-webroot step (success) 02:14:19 INFO - [mozharness: 2016-12-28 10:14:19.781759Z] Running create-virtualenv step. 02:14:19 INFO - Running pre-action listener: _resource_record_pre_action 02:14:19 INFO - Running main action method: create_virtualenv 02:14:19 INFO - Creating virtualenv /builds/slave/test/build/venv 02:14:19 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 02:14:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:14:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:14:19 INFO - Using real prefix '/tools/python27' 02:14:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:14:20 INFO - Installing distribute.............................................................................................................................................................................................done. 02:14:23 INFO - Installing pip.................done. 02:14:23 INFO - Return code: 0 02:14:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:14:23 INFO - Reading from file tmpfile_stdout 02:14:23 INFO - Output received: 02:14:23 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) 02:14:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:14:23 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')]} 02:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:23 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')]} 02:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:14:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:23 INFO - 'HOME': '/Users/cltbld', 02:14:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:23 INFO - 'LOGNAME': 'cltbld', 02:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:23 INFO - 'MOZ_NO_REMOTE': '1', 02:14:23 INFO - 'NO_EM_RESTART': '1', 02:14:23 INFO - 'PAGER': '/bin/cat', 02:14:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:23 INFO - 'PWD': '/builds/slave/test', 02:14:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:23 INFO - 'SHELL': '/bin/bash', 02:14:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:23 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:23 INFO - 'USER': 'cltbld', 02:14:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:23 INFO - 'XPC_FLAGS': '0x0', 02:14:23 INFO - 'XPC_SERVICE_NAME': '0', 02:14:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:24 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 02:14:24 INFO - Cleaning up... 02:14:24 INFO - Return code: 0 02:14:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:24 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 02:14:24 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 02:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:24 INFO - 'HOME': '/Users/cltbld', 02:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:24 INFO - 'LOGNAME': 'cltbld', 02:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:24 INFO - 'MOZ_NO_REMOTE': '1', 02:14:24 INFO - 'NO_EM_RESTART': '1', 02:14:24 INFO - 'PAGER': '/bin/cat', 02:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:24 INFO - 'PWD': '/builds/slave/test', 02:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:24 INFO - 'SHELL': '/bin/bash', 02:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:24 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:24 INFO - 'USER': 'cltbld', 02:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:24 INFO - 'XPC_FLAGS': '0x0', 02:14:24 INFO - 'XPC_SERVICE_NAME': '0', 02:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:24 INFO - Downloading/unpacking psutil>=3.1.1 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:14:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:14:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:14:28 INFO - Installing collected packages: psutil 02:14:28 INFO - Running setup.py install for psutil 02:14:28 INFO - building 'psutil._psutil_osx' extension 02:14:28 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 02:14:28 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 02:14:29 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 02:14:29 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 02:14:29 INFO - building 'psutil._psutil_posix' extension 02:14:29 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 02:14:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:14:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:14:29 INFO - ^ 02:14:29 INFO - 1 warning generated. 02:14:29 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 02:14:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:14:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:14:29 INFO - Successfully installed psutil 02:14:29 INFO - Cleaning up... 02:14:29 INFO - Return code: 0 02:14:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:29 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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:29 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 02:14:29 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 02:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:29 INFO - 'HOME': '/Users/cltbld', 02:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:29 INFO - 'LOGNAME': 'cltbld', 02:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:29 INFO - 'MOZ_NO_REMOTE': '1', 02:14:29 INFO - 'NO_EM_RESTART': '1', 02:14:29 INFO - 'PAGER': '/bin/cat', 02:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:29 INFO - 'PWD': '/builds/slave/test', 02:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:29 INFO - 'SHELL': '/bin/bash', 02:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:29 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:29 INFO - 'USER': 'cltbld', 02:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:29 INFO - 'XPC_FLAGS': '0x0', 02:14:29 INFO - 'XPC_SERVICE_NAME': '0', 02:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:14:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:14:32 INFO - Installing collected packages: mozsystemmonitor 02:14:32 INFO - Running setup.py install for mozsystemmonitor 02:14:32 INFO - Successfully installed mozsystemmonitor 02:14:32 INFO - Cleaning up... 02:14:32 INFO - Return code: 0 02:14:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:32 INFO - 'HOME': '/Users/cltbld', 02:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:32 INFO - 'LOGNAME': 'cltbld', 02:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:32 INFO - 'MOZ_NO_REMOTE': '1', 02:14:32 INFO - 'NO_EM_RESTART': '1', 02:14:32 INFO - 'PAGER': '/bin/cat', 02:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:32 INFO - 'PWD': '/builds/slave/test', 02:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:32 INFO - 'SHELL': '/bin/bash', 02:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:32 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:32 INFO - 'USER': 'cltbld', 02:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:32 INFO - 'XPC_FLAGS': '0x0', 02:14:32 INFO - 'XPC_SERVICE_NAME': '0', 02:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:33 INFO - Downloading/unpacking jsonschema==2.5.1 02:14:33 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 02:14:33 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 02:14:33 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 02:14:33 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 02:14:33 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 02:14:33 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 02:14:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:14:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:14:35 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 02:14:35 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 02:14:35 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 02:14:35 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 02:14:35 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 02:14:35 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 02:14:36 INFO - Downloading functools32-3.2.3-2.tar.gz 02:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:14:36 INFO - warning: no files found matching '*.txt' 02:14:36 INFO - no previously-included directories found matching 'build' 02:14:36 INFO - no previously-included directories found matching 'dist' 02:14:36 INFO - no previously-included directories found matching '.git*' 02:14:36 INFO - Installing collected packages: jsonschema, functools32 02:14:36 INFO - Running setup.py install for functools32 02:14:36 INFO - warning: no files found matching '*.txt' 02:14:36 INFO - no previously-included directories found matching 'build' 02:14:36 INFO - no previously-included directories found matching 'dist' 02:14:36 INFO - no previously-included directories found matching '.git*' 02:14:36 INFO - Successfully installed jsonschema functools32 02:14:36 INFO - Cleaning up... 02:14:36 INFO - Return code: 0 02:14:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:36 INFO - 'HOME': '/Users/cltbld', 02:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:36 INFO - 'LOGNAME': 'cltbld', 02:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:36 INFO - 'MOZ_NO_REMOTE': '1', 02:14:36 INFO - 'NO_EM_RESTART': '1', 02:14:36 INFO - 'PAGER': '/bin/cat', 02:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:36 INFO - 'PWD': '/builds/slave/test', 02:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:36 INFO - 'SHELL': '/bin/bash', 02:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:36 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:36 INFO - 'USER': 'cltbld', 02:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:36 INFO - 'XPC_FLAGS': '0x0', 02:14:36 INFO - 'XPC_SERVICE_NAME': '0', 02:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:14:36 INFO - Cleaning up... 02:14:36 INFO - Return code: 0 02:14:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:36 INFO - 'HOME': '/Users/cltbld', 02:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:36 INFO - 'LOGNAME': 'cltbld', 02:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:36 INFO - 'MOZ_NO_REMOTE': '1', 02:14:36 INFO - 'NO_EM_RESTART': '1', 02:14:36 INFO - 'PAGER': '/bin/cat', 02:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:36 INFO - 'PWD': '/builds/slave/test', 02:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:36 INFO - 'SHELL': '/bin/bash', 02:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:36 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:36 INFO - 'USER': 'cltbld', 02:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:36 INFO - 'XPC_FLAGS': '0x0', 02:14:36 INFO - 'XPC_SERVICE_NAME': '0', 02:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:14:36 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 02:14:36 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 02:14:36 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 02:14:36 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 02:14:36 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 02:14:36 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 02:14:39 INFO - Downloading blobuploader-1.2.4.tar.gz 02:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:14:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:14:39 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 02:14:39 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 02:14:39 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 02:14:39 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 02:14:39 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 02:14:39 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 02:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:14:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:14: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 02:14: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 02:14: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 02:14: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 02:14: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 02:14: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 02:14:40 INFO - Downloading docopt-0.6.1.tar.gz 02:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:14:40 INFO - Installing collected packages: blobuploader, requests, docopt 02:14:40 INFO - Running setup.py install for blobuploader 02:14:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:14:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:14:40 INFO - Running setup.py install for requests 02:14:41 INFO - Running setup.py install for docopt 02:14:41 INFO - Successfully installed blobuploader requests docopt 02:14:41 INFO - Cleaning up... 02:14:41 INFO - Return code: 0 02:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:41 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')]} 02:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:41 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')]} 02:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:41 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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:41 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 02:14:41 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 02:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:41 INFO - 'HOME': '/Users/cltbld', 02:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:41 INFO - 'LOGNAME': 'cltbld', 02:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:41 INFO - 'MOZ_NO_REMOTE': '1', 02:14:41 INFO - 'NO_EM_RESTART': '1', 02:14:41 INFO - 'PAGER': '/bin/cat', 02:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:41 INFO - 'PWD': '/builds/slave/test', 02:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:41 INFO - 'SHELL': '/bin/bash', 02:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:41 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:41 INFO - 'USER': 'cltbld', 02:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:41 INFO - 'XPC_FLAGS': '0x0', 02:14:41 INFO - 'XPC_SERVICE_NAME': '0', 02:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-uakLFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Zw3ky_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-DifVst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pMoGzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Yt2gsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-WoaGpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JOkr4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-b_mQkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-K8AlFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:42 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Te9sk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-b_bBC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-X_7PRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-v_sPjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xjXl9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-VPKYl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-chOYSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:43 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-mnSipi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:14:43 INFO - Running setup.py install for manifestparser 02:14:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:14:43 INFO - Running setup.py install for mozcrash 02:14:44 INFO - Running setup.py install for mozdebug 02:14:44 INFO - Running setup.py install for mozdevice 02:14:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Running setup.py install for mozfile 02:14:44 INFO - Running setup.py install for mozhttpd 02:14:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Running setup.py install for mozinfo 02:14:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Running setup.py install for mozInstall 02:14:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:14:44 INFO - Running setup.py install for mozleak 02:14:45 INFO - Running setup.py install for mozlog 02:14:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Running setup.py install for moznetwork 02:14:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Running setup.py install for mozprocess 02:14:45 INFO - Running setup.py install for mozprofile 02:14:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Running setup.py install for mozrunner 02:14:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:14:45 INFO - Running setup.py install for mozscreenshot 02:14:46 INFO - Running setup.py install for moztest 02:14:46 INFO - Running setup.py install for mozversion 02:14:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:14:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:14:46 INFO - Cleaning up... 02:14:46 INFO - Return code: 0 02:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:46 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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:46 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 02:14:46 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 02:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:46 INFO - 'HOME': '/Users/cltbld', 02:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:46 INFO - 'LOGNAME': 'cltbld', 02:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:46 INFO - 'MOZ_NO_REMOTE': '1', 02:14:46 INFO - 'NO_EM_RESTART': '1', 02:14:46 INFO - 'PAGER': '/bin/cat', 02:14:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:46 INFO - 'PWD': '/builds/slave/test', 02:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:46 INFO - 'SHELL': '/bin/bash', 02:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:46 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:46 INFO - 'USER': 'cltbld', 02:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:46 INFO - 'XPC_FLAGS': '0x0', 02:14:46 INFO - 'XPC_SERVICE_NAME': '0', 02:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-NeanDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:46 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)) 02:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hRwT22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:46 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)) 02:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-m4_YS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:46 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)) 02:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:46 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-IwVI0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-qT3XKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pWSpwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-n7I5xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xWGkPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kclvwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-vhEpy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-7WVBZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0rbM_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:47 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)) 02:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-S9JUDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:48 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)) 02:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-8WkCk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:48 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)) 02:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xlfajw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:48 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)) 02:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0Quyln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:48 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)) 02:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hnSGSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:48 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)) 02:14:48 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)) 02:14:48 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)) 02:14:48 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)) 02:14:48 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)) 02:14:48 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)) 02:14:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:14: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 02:14: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 02:14: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 02:14: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 02:14: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 02:14: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 02:14:51 INFO - Downloading blessings-1.6.tar.gz 02:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:14:51 INFO - Installing collected packages: blessings 02:14:51 INFO - Running setup.py install for blessings 02:14:51 INFO - Successfully installed blessings 02:14:51 INFO - Cleaning up... 02:14:51 INFO - Return code: 0 02:14:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:14:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:51 INFO - Reading from file tmpfile_stdout 02:14:51 INFO - Current package versions: 02:14:51 INFO - blessings == 1.6 02:14:51 INFO - blobuploader == 1.2.4 02:14:51 INFO - docopt == 0.6.1 02:14:51 INFO - functools32 == 3.2.3-2 02:14:51 INFO - jsonschema == 2.5.1 02:14:51 INFO - manifestparser == 1.1 02:14:51 INFO - mozInstall == 1.12 02:14:51 INFO - mozcrash == 1.0 02:14:51 INFO - mozdebug == 0.1 02:14:51 INFO - mozdevice == 0.48 02:14:51 INFO - mozfile == 1.2 02:14:51 INFO - mozhttpd == 0.7 02:14:51 INFO - mozinfo == 0.9 02:14:51 INFO - mozleak == 0.1 02:14:51 INFO - mozlog == 3.4 02:14:51 INFO - moznetwork == 0.27 02:14:51 INFO - mozprocess == 0.23 02:14:51 INFO - mozprofile == 0.28 02:14:51 INFO - mozrunner == 6.13 02:14:51 INFO - mozscreenshot == 0.1 02:14:51 INFO - mozsystemmonitor == 0.3 02:14:51 INFO - moztest == 0.7 02:14:51 INFO - mozversion == 1.4 02:14:51 INFO - psutil == 3.1.1 02:14:51 INFO - requests == 1.2.3 02:14:51 INFO - wsgiref == 0.1.2 02:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:51 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')]} 02:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:51 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')]} 02:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:51 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 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14:51 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 02:14:51 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 02:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:51 INFO - 'HOME': '/Users/cltbld', 02:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:51 INFO - 'LOGNAME': 'cltbld', 02:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:51 INFO - 'MOZ_NO_REMOTE': '1', 02:14:51 INFO - 'NO_EM_RESTART': '1', 02:14:51 INFO - 'PAGER': '/bin/cat', 02:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:51 INFO - 'PWD': '/builds/slave/test', 02:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:51 INFO - 'SHELL': '/bin/bash', 02:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:51 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:51 INFO - 'USER': 'cltbld', 02:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:51 INFO - 'XPC_FLAGS': '0x0', 02:14:51 INFO - 'XPC_SERVICE_NAME': '0', 02:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:51 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)) 02:14:52 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)) 02:14:52 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)) 02:14:52 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)) 02:14:52 INFO - Cleaning up... 02:14:52 INFO - Return code: 0 02:14:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:14: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')]} 02:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14: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')]} 02:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109735570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa15c8270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, '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.qt1yj28joN/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.5ag5Wo7L7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 02:14: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', 'jsonschema'] in /builds/slave/test/build 02:14: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 jsonschema 02:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:52 INFO - 'HOME': '/Users/cltbld', 02:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:52 INFO - 'LOGNAME': 'cltbld', 02:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:52 INFO - 'MOZ_NO_REMOTE': '1', 02:14:52 INFO - 'NO_EM_RESTART': '1', 02:14:52 INFO - 'PAGER': '/bin/cat', 02:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:52 INFO - 'PWD': '/builds/slave/test', 02:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:52 INFO - 'SHELL': '/bin/bash', 02:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:14:52 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:52 INFO - 'USER': 'cltbld', 02:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:52 INFO - 'XPC_FLAGS': '0x0', 02:14:52 INFO - 'XPC_SERVICE_NAME': '0', 02:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:14:52 INFO - Cleaning up... 02:14:52 INFO - Return code: 0 02:14:52 INFO - Running post-action listener: _resource_record_post_action 02:14:52 INFO - Running post-action listener: _start_resource_monitoring 02:14:52 INFO - Starting resource monitoring. 02:14:52 INFO - [mozharness: 2016-12-28 10:14:52.307557Z] Finished create-virtualenv step (success) 02:14:52 INFO - [mozharness: 2016-12-28 10:14:52.308544Z] Running install step. 02:14:52 INFO - Running pre-action listener: _resource_record_pre_action 02:14:52 INFO - Running main action method: install 02:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:52 INFO - Reading from file tmpfile_stdout 02:14:52 INFO - Detecting whether we're running mozinstall >=1.0... 02:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:14:52 INFO - Reading from file tmpfile_stdout 02:14:52 INFO - Output received: 02:14:52 INFO - Usage: mozinstall [options] installer 02:14:52 INFO - Options: 02:14:52 INFO - -h, --help show this help message and exit 02:14:52 INFO - -d DEST, --destination=DEST 02:14:52 INFO - Directory to install application into. [default: 02:14:52 INFO - "/builds/slave/test"] 02:14:52 INFO - --app=APP Application being installed. [default: firefox] 02:14:52 INFO - mkdir: /builds/slave/test/build/application 02:14:52 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'] 02:14:52 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 02:15:23 INFO - Reading from file tmpfile_stdout 02:15:23 INFO - Output received: 02:15:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:15:23 INFO - Running post-action listener: _resource_record_post_action 02:15:23 INFO - [mozharness: 2016-12-28 10:15:23.782907Z] Finished install step (success) 02:15:23 INFO - [mozharness: 2016-12-28 10:15:23.783057Z] Running run-tests step. 02:15:23 INFO - Running pre-action listener: _resource_record_pre_action 02:15:23 INFO - Running main action method: run_tests 02:15:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:15:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:15:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:15:23 INFO - 2016-12-28 02:15:23.797 screenresolution[1628:8040] starting screenresolution argv=screenresolution get 02:15:23 INFO - 2016-12-28 02:15:23.800 screenresolution[1628:8040] Display 0: 1600x1200x32@60 02:15:23 INFO - 2016-12-28 02:15:23.806 screenresolution[1629:8042] starting screenresolution argv=screenresolution list 02:15:23 INFO - Available Modes on Display 0 02:15:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:15:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:15:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:15:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:15:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:15:24 INFO - Intel Iris: 02:15:24 INFO - Chipset Model: Intel Iris 02:15:24 INFO - Type: GPU 02:15:24 INFO - Bus: Built-In 02:15:24 INFO - VRAM (Dynamic, Max): 1536 MB 02:15:24 INFO - Vendor: Intel (0x8086) 02:15:24 INFO - Device ID: 0x0a2e 02:15:24 INFO - Revision ID: 0x0009 02:15:24 INFO - Displays: 02:15:24 INFO - Display: 02:15:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:15:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:15:24 INFO - Display Serial Number: 1600x1200 60 02:15:24 INFO - Main Display: Yes 02:15:24 INFO - Mirror: Off 02:15:24 INFO - Online: Yes 02:15:24 INFO - Rotation: Supported 02:15:24 INFO - Return code: 0 02:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:15:24 INFO - Python 2.7.3 02:15:24 INFO - Return code: 0 02:15:24 INFO - grabbing minidump binary from tooltool 02:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:15:24 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 0x1095d3d30>, '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 0x109439db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095cbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:15:24 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 02:15:24 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 02:15:24 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 02:15:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:15:24 INFO - Return code: 0 02:15:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:15:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:15:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:15:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:15:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:15:24 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/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 02:15:24 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/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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 02:15:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qt1yj28joN/Render', 02:15:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:24 INFO - 'HOME': '/Users/cltbld', 02:15:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:24 INFO - 'LOGNAME': 'cltbld', 02:15:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:15:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:24 INFO - 'MOZ_NO_REMOTE': '1', 02:15:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:15:24 INFO - 'NO_EM_RESTART': '1', 02:15:24 INFO - 'PAGER': '/bin/cat', 02:15:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:24 INFO - 'PWD': '/builds/slave/test', 02:15:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:15:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:24 INFO - 'SHELL': '/bin/bash', 02:15:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ag5Wo7L7E/Listeners', 02:15:24 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 02:15:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:24 INFO - 'USER': 'cltbld', 02:15:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:24 INFO - 'XPC_FLAGS': '0x0', 02:15:24 INFO - 'XPC_SERVICE_NAME': '0', 02:15:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:24 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/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 02:15:24 INFO - mozversion application_buildid: 20161228004005 02:15:24 INFO - mozversion application_changeset: 2c316d8a97781a4c47d648bc87a00ff1d5155749 02:15:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:15:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:15:24 INFO - mozversion application_name: Firefox 02:15:24 INFO - mozversion application_remotingname: firefox-dev 02:15:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:15:24 INFO - mozversion application_vendor: Mozilla 02:15:24 INFO - mozversion application_version: 52.0a2 02:15:24 INFO - mozversion platform_buildid: 20161228004005 02:15:24 INFO - mozversion platform_changeset: 2c316d8a97781a4c47d648bc87a00ff1d5155749 02:15:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:15:24 INFO - mozversion platform_version: 52.0a2 02:15:24 INFO - using testdate: 1482920124 02:15:24 INFO - actual date: 1482920124 02:15:24 INFO - starting webserver on 'localhost:49247' 02:15:24 INFO - SUITE-START | Running 2 tests 02:15:24 INFO - TEST-START | dromaeo_css 02:15:24 INFO - operating with platform_type : mac_ 02:15:24 INFO - Initialising browser for dromaeo_css test... 02:15:24 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpop2KHe/profile http://localhost:49247/getInfo.html) 02:15:27 INFO - PROCESS | 1639 | 2016-12-28 02:15:27.524 plugin-container[1643:8234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 02:15:27 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:15:27 INFO - PROCESS | 1639 | 2016-12-28 02:15:27.525 plugin-container[1643:8234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d0b, name = 'com.apple.CFPasteboardClient' 02:15:27 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:15:27 INFO - PROCESS | 1639 | 2016-12-28 02:15:27.525 plugin-container[1643:8234] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:15:27 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 02:15:27 INFO - PROCESS | 1639 | colorDepth:24 02:15:27 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 02:15:27 INFO - PROCESS | 1639 | __metrics 02:15:27 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:15:28 INFO - TEST-INFO | 1639: exit 0 02:15:28 INFO - Browser initialized. 02:15:28 INFO - Running cycle 1/1 for dromaeo_css test... 02:15:28 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpop2KHe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:15:31 INFO - PROCESS | 1644 | 2016-12-28 02:15:31.043 plugin-container[1645:8361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 02:15:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:15:31 INFO - PROCESS | 1644 | 2016-12-28 02:15:31.043 plugin-container[1645:8361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 02:15:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:15:31 INFO - PROCESS | 1644 | 2016-12-28 02:15:31.043 plugin-container[1645:8361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:15:40 INFO - PROCESS | 1644 | RSS: Main: 229576704 02:15:40 INFO - PROCESS | 1644 | 02:16:30 INFO - PROCESS | 1644 | 1482920190381 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] 02:18:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 02:18:45 INFO - PROCESS | 1644 | RSS: Main: 232771584 02:18:45 INFO - PROCESS | 1644 | 02:21:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 02:21:48 INFO - PROCESS | 1644 | RSS: Main: 243609600 02:21:48 INFO - PROCESS | 1644 | 02:24:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 02:24:51 INFO - PROCESS | 1644 | RSS: Main: 246382592 02:24:51 INFO - PROCESS | 1644 | 02:27:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 02:27:53 INFO - PROCESS | 1644 | RSS: Main: 245702656 02:27:53 INFO - PROCESS | 1644 | 02:30:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 02:30:57 INFO - PROCESS | 1644 | RSS: Main: 249118720 02:30:57 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 02:34:00 INFO - PROCESS | 1644 | RSS: Main: 250654720 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | __start_tp_report 02:34:00 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:34:00 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:34:00 INFO - PROCESS | 1644 | |i|pagename|runs| 02:34:00 INFO - PROCESS | 1644 | |0;dojo.html;2461.4461746107118;2512.6733524641804;3358.244395011123;3361.26052268501;3376.4935259711046;4072.755634661922;4869.975650121749;5689.402612725661;5696.145578163268;5700.429957004298;5641.830745077644;5646.153077038446;5657.745401456934;5663.7168141592965;5671.177679236508;5152.227165925123;5152.304438900756;5221.556167725748;5240.685558866461;5262.184361423985;3770.490983717204;4794;5190.844274671747;5253.60597955153;5267.3942496613;5123.4108077571;5147.305113809644;5203.2975548301065;5214.661047031931;5248.790048398078;4962.255661650745;5148.74256287186;5195;5221.73891305437;5254.369475662907;4238.258304796648;5141.100307446182;5156.948430515685;5167.948320516784;5183.248428977777;5581.609287349887;5659.547236221092;5672.4611161939965;5695.601307908448;5703.828885133453;5970.55220858433;6100.944524685187;6114.2365411686305;6133.846653833645;6136.028952951454;6114.145302971524;6186.876262474771;6191.721372538246;6265.686715664198;6306.747730090791;5705.657660540381;5726.226959360475;5737.254156959611;5753.597248192628;5762.74067666956;6145.770845830852;6249.9062514062325;6260.311365749764;6270.811875643738;6283.622982621057;5974.3726908674835;6084.117802918552;6090.33006369299;6097.207363042776;6126.754929802847;6111.318268824403;6141.5473781508945;6163.445289923929;6165.229346331708;6182.567220294536;4223.915521689549;5892.6759028253;5896.086106653475;5896.911546326808;5911.201987731602;5657.377688454267;5959.165716799652;5992.580519363602;6038.365971573009;6076.939230607726;4849.563539281411;4871.658983871095;4907.779149938261;4951.455339912607;4988.501149884983;3780.716446266486;3943.132510847609;4329.567043295645;4407.184670835842;4409.779511024436;5586.497215250647;5667.348254950651;5853.209816674779;5861.17943487912;5885.382034886361;5443.455654434604;5873.6490607160595;6124.960759790047;6172.814815555541;6183.505319574423;5758.222639943554;5784.161296612052;5791.942080579224;5800.390958949334;5832.096025116114;5387.973060134753;5399.460053994648;5413.539849112791;5417.891642167135;5417.972910135424;6234.314225435108;6237.532185086227;6256.906146407808;6274.027525733519;6282.120503129474;5078.56832169255;5306.310179676771;5380.865478363072;5659.613394718156;6263.561550691408;5163.690178589297;5736.627119237236;5792.9283082629845;6092.725827337869;6329.240491141092;4584.541545845389;5817.156512305776;6126.029144987462;6126.884121540821;6127.142200091902;5231.607629427732;5729.169270455677;6119.388061194023;6235.563510554218;6239.532035097477;5162.277281180713;5939.227900372924;6113.633182009094;6144.539159562962;6145.385461453998;5945.375735547878;6008.849778755566;6016.187814644967;6043.969780151071;6103.115048318058;5797.2173756542325;5885.646861188342;5905.822825315247;5948.405159484017;6006.09908513709;4923.310736496965;6112.321110680095;6129.2644882612585;6154.1999540059505;6205.503559715122;6023.3073196582955;6027.186329845415;6032.879342413039;6039.214902062704;6044.274687037408 02:34:00 INFO - PROCESS | 1644 | |1;ext.html;451.6409454483686;463.77738685431;492.6379111353155;525.6950968438306;584.4068270705237;30005.699943000564;38857.611423885755;38883.41674874876;38897.8330650081;38933.99999999996;40318.39522407159;40358;40422.78731638048;40470.392944105864;40488.595114048854;9164.954175229132;9386.108319709616;9406.576704048335;9456.338056336059;9513.524323783819;9315.580798864034;9418.058194180561;9429.774129363173;9437.009114042996;9449.05509449053;9363.906360936371;9448.905510944905;9451.432914025181;9476.952615236914;9483.525823708822;9352.953235233814;9423.717288481357;9439.19766819821;9443.622255109787;9450.43297402158;9330.393524420959;9436.905630943671;9446.433214007113;9447.338686291894;9447.433154010712;37480;37824.43263351052;37871;37886;38031.80984095062;3402.2515046689687;3439.329330780499;3443.6728510791436;3851.6726078283377;3910.4720862683744;28755.28111797222;29061.273468163254;29459.410811783644;29485.705142948726;29495.263425275694;3167.984160079208;3175.3966746318124;3197.888073917425;3208.5347624594538;3228.1929517620597;484866;485455;492808;625756;626899.9999999955;60558.69720651369;61153;61237;61347.38652613506;61453.69273153606;63536;64088.35911640824;64295;64327;64477.35522644714;13986.370613322424;15558.455454059054;15642.296096675676;15661.138637375054;16136.838631613533;9974.451405172786;12438.751224975449;12478.5008599655;12481.500739970299;12483.188592741442;18184.545386365447;18297.176627051813;18503;18528.907355463136;18538.907305463657;6708.901235061388;7502.099748030271;7733.188015258429;7770.378369730408;7820.413468989848;744.728174216418;761.5963539324056;866.4801119328353;869.7651634058768;899.6131663384676;28508.857455713005;28718.569221461694;29244.415111698072;29816.850915745283;29898;822.3873214455269;981.4945303168873;989.8564708117283;1009.6814707132975;1092.4100985467917;14350;15013.624659383384;15400.663821716544;15518.844811551966;15563.766543501857;655606;657868;663678;666159;667565;55895.72052139713;56272.71863640737;56325;56418.435815641315;56680;1203.4163430736087;1248.8563815161372;1267.2143271171933;1314.7764879970428;1317.6639956810952;23967.76032239725;31355;31475;32034.03897883067;32040.359192815547;51742;52112;52227.69430764261;52389;52581;529494;533913;534716;535781;535826;25351.746482534938;25527;25813.870930645226;25835.22494325173;25958.610620840325;44159;44358.3346249813;44415.77792111019;44957.775211123735;45068.54931450643;37660.811695941346;37823.243535129695;37932.4702103607;38085.80957095197;38181.80909095548;29629;37384.626153739206;38170.427443588924;38170.809145954096;38203.617963820005;33516.329673406886;36092;36838.63161368352;37009.81495092615;38531.6146838528;32217;33919;34011.4898276531;34342.969710908714;40711.38932916068;27497.45005099927;29291.12126636119;30614;30651.2337191572;32361.676383235867 02:34:00 INFO - PROCESS | 1644 | |2;jquery.html;309.5928853557572;362.31160794490484;368.30574367317615;370.06373874098523;399.73817149766916;18900.527486812836;19862.708923919952;19897.30359437418;19969.500762480944;20177;16617.00297982122;19743;19869.708468949546;20083.497912552193;20343.898280508613;5135.486451354854;5644.8306550803545;5775.306963164426;6075.513958883279;6103.206583144185;4916.655834091615;5041.294218809369;5078.162103252959;6002.609830361012;6016.578839481238;5785.797497087603;5787.913181302284;5957;5961.672108034057;5970.925233457976;5095.363079615048;5383.596230282706;5653.349864765563;5817.389174136718;5941.970290148543;4467.7542735149555;5033.094043072246;5148.601703574129;5155.381354237478;5168.22476628505;17477.475675729747;20202.49493762653;25221.49557008868;25263.747362526323;25348.11281605135;2261.6268315727884;2269.5347453771938;2364.408897775556;2620.344913771557;2722.3194201449637;14869.851301486955;14954;15218.239088045553;15431.459898903597;15506.61233469161;1782.643471305744;1793.9103044847836;1834.1562881074722;1871.1392759330724;1901.610169915164;112505.43747281293;117003;117936.41031794871;118283.40858295739;118313.40843295728;9579.712608621752;10648.361098334124;11117.944410277896;11400.315981041163;11529.596464123882;13393.59819205406;13811.033227673966;13816.585502434944;13828.723425531432;13838.377273022737;8300.916990830136;8543.188397102265;8553.700620478254;8674.872266605427;8689.131086891386;8490.27832634233;8583.356248281403;8590.570471476527;8694.608742606599;8728.389012769172;5394.514493695508;5590.97204513983;5933.792317268875;6193.349698281706;6585.341465853472;1566.498720409458;1824.224704500582;1847.131848031423;1849.1309084730156;1851.9628408750266;887.6981826179131;927.8979312275644;953.7329547726509;958.1328897347911;981.1562056631292;17400.129993500526;20833.270835520685;21685.566288674137;21759.129634814748;22028.88985555062;760.5626764610371;881.8192270584515;899.8875140607424;903.2277402820494;906.063155588965;9931.404115752934;11002.284851484628;11008.064314533354;11677.474513646906;11718.88281117195;598322;601785;602928;615019;617782;82397;114577;118333;121570;125298;898.1377877237794;974.4104816586005;979.9216062715109;993.2947607198826;1032.2258306270298;18321.267149313877;18747.90626046861;19176.328828490936;19196.61606767829;19228;46064.53935460602;47348.2897756527;47878.28182577195;48409.75795121002;49035;64441;64796.704065917475;66878;68114;68629;6521.543491955707;7565.394768418623;7857.174996625272;7905.288524032865;8087.353011758927;12678.161666558482;12851.871481285067;12887.29119898377;13150.539731109748;13327;9497.810043798947;10908.509117089909;11373.601923932623;11445.713857153638;11461;10801.945990269998;11112.277701949348;11226.438678066228;11394;11427.085833133164;10838.458077095956;11281.887181128412;11286.717832054264;11672.533098675958;11852.288862668267;7846.293833555007;7893.8159276109045;7935.960320198593;8036.598170091589;8092;11176.888231117584;11214.215004949901;11325.490352933974;11344.149188810707;11479.36863472517 02:34:00 INFO - PROCESS | 1644 | |3;mootools.html;1428.4072110074312;1432.7492688779462;1442.1491320121127;1587.9122800881398;1700.7533907583409;7534.6232688365635;7840.490368126068;8107.6756929722815;8582.527960962145;8584.69953551625;8011.919880801205;8057.838843223132;8381;8387.538685372301;8469.153084691528;2339.3566769138497;2342.3090188394463;2346.2022912209845;2350.3888988862846;2382.8570285782907;2344.6014177589764;2380.238323736405;2381.535600557892;2409.8072154227707;2410.216679579135;2329.1964272326104;2341.356127065054;2350.4828937633692;2371.5019845832394;2379.286214135761;2233.396982814631;2236.8769717665523;2260.480089579398;2261.208576998054;2280.9543809123725;1978.9604207915904;2359.091749676387;2368.9694982682418;2378.4291769975252;2379.8810059496955;8416.747497575083;8469.983601967742;8513;8516.8722469163;8546.829063418696;5093;5251.238570407269;5257.290265814105;5258.3689957205;5282.97358513205;3094.133642580081;3301.834908254578;3434.6737059979264;3607.0080727799805;3619.1675914539805;887.4719541872576;951.2247518272586;996.8853581838109;1079.0504356166523;1124.831275308702;9794.853077203848;9875.456849873182;11062.668119956414;11150.776984460346;11154.60958866444;4452.5529203009155;4458.264386376277;4532.773361331921;4630.74530900797;4641.489436162019;8706.912930870736;9504.90495095054;9590;9599;9613.951930240444;5211;5211.5830733541225;5222.503862133091;5232.084385232569;5247.501487358695;4219.346001369792;4249.46881639795;4702.882427939329;4755.881102972384;4787.569118779326;3837.7505462144873;3874.85691720943;3947.842086316515;3959.9604003960167;3999.8200080996426;1276.0875973678865;1278.9680257993625;1288.2721262486853;1294.6763309172707;1301.8828305452555;794.7774623105539;806.1736719862188;889.5107690770051;898.820235952799;907.4827348411342;5599.132134519156;5625.184348269559;5697.4587414195585;5858.091995740667;5897.32180799205;943.6225509796136;958.4153666263574;985.3398223190479;1006.6778630838062;1025.410389026313;3581.1405262737276;3661.3409586274192;3665.193657395369;3710.0353907984118;3753.981230093832;9043.773905652411;9740.805183896282;9946.602135914627;10238.897611023944;10252.948735256276;7856.489328193649;8995.505247211466;9543.904560954441;10141.340812847142;10179.54192061359;7701.537907725442;7999.800004999805;8009.719659811879;8015.7595272141925;8019.679212831421;4976.751162441936;5336.412994570516;5692;5845.269341332333;5875.529957603467;9228.676996305097;9338.019507951567;9429.00995395501;9466;9483.241340692866;9570.090841370058;9656.275779316438;9693.22454203648;9723.367981081206;9833.803323933624;6755.189377274562;6982.371586557234;7205.207427183111;7258.963705181441;7405.148407933157;6625.867482650417;7258.419326453976;8067.193280671886;8134.430589858653;8176.550289734122;5835.970820146042;7183;7280.3083707047745;7366.7053317866685;7449.404047676065;6875.037494750839;6997.860042799217;7051.788446346618;7144.499885007999;7187.5687458752645;6375.681215939092;6763.2898545652;6772.864542709217;6804.319568043156;6861.210960739579;4917.729524876167;4923.064617722621;4937.901241975069;4953.702777833198;4981.850544483671;4901.779419926041;4942.1598328285;4973.776180071978;4983.725895075806;5030 02:34:00 INFO - PROCESS | 1644 | |4;prototype.html;288.5311369025334;295.0572919522128;300.29730430791955;301.117725065558;302.84403532180926;4775;4845.127876982142;5183.740812959351;5187.533122019013;5204.828913494466;5096.77064532096;5328.813491527797;5342.679439233639;5345.1982202669615;5373.785048598061;3251.463508521091;4071.307877660797;4139.337705967046;4156.91686166278;4170.353595192735;3973.1854969731135;4055.655269302098;4133.9173216535655;4160.39674247234;4201.831926722943;3384.627690953993;4068.5321188063454;4093.9181216375655;4114.732542384751;4115.991582062399;3763.7929913854728;4094.406311084891;4114.917701645951;4118.938215926763;4147.605977432139;2989.6412430508262;3604.9098772530633;3618.6019537850816;3624.963750362489;3627.4014787560022;4826.600285917079;5039.193729003378;5232.189010703347;5254.395744489394;5259.2899958505495;4705.129551033036;4706;4712.32712387101;4718.245080787057;4727.905441891177;5654.406287339812;5689.573282003825;5734.139879018139;5743.569232307594;5757.783253447453;2985.134311049793;3547.5210846535647;3626.8186590670625;3750.7374483786148;3868.883933482;5010.69935803853;6029.366916473751;6041.7010342776075;6051.727672254715;6096.756129754849;3340.6993370596765;3948.822302996372;3966.186931678999;3971.339202734175;3997.300472417315;3892.766434013968;3947.1513624570857;4000.6199411056486;4007.4771586796833;4014.297497937849;4137.91724165515;4303.655707543389;4646.326282688991;4687.1328804171335;4699.436067671902;4763.666543341933;5779.739911703899;5809.60138319088;5864.765409383662;5877.030290002187;1191.53530123252;1233.8396008518835;1260.6322140477685;1282.5318758653211;1290.4709069281737;758.4993904023429;852.6400391375746;873.912608739121;880.9471431713989;886.9157430044264;4621.32990716351;4670.462896766848;4890.139268527951;5047.848564543069;5157.406898206679;4454.57681520255;5052.621053421009;5172.456892026358;5257.526822585985;5648.237487939158;4642.303654451859;4857.295692124622;5073.619478539124;5143.354126679502;5208.661437006583;2823.8729257183477;2916.9708302917124;2919.9708002920124;2946.764258859286;2949.336399310138;5082.110630639623;5691.630044047207;5826.950269562613;5863.7947671832135;5864.266966629171;5167.612429067835;5323.441038690882;5666;5729.598928074995;5806.82579522615;5552.666839989613;5582.944170558324;5618.539179813278;5829.854253643693;5881.882362352814;4527.818887244605;5526.364468086222;5632.493075623213;5643.971780141237;5711.428857114255;5138.537531622172;5292.682439053669;5428.995635807389;5477.029153863417;5522.171674248895;5390.595705322038;5473.808416705396;5824.94175058244;5830.271216097988;5846.269216347957;3433.759636825398;3498.5276987607367;3712.498812660256;3752.5684546276434;3769.3780526212868;5153.793848246178;5532.723363831873;6040.879182416415;6042.969785151046;6048.818535443944;5610.607257491936;5647.350554686263;5716.314042314949;5735.827925162252;5820.476157145878;5070.670406423718;5136.5858046717985;5808.36108028125;5831.79588714393;5834.416558344132;5315.149576067811;5417.783288668409;5621.072523033654;5722.542196624343;5740.942590574041;4655.11552804966;5312.654677445954;5802.7098645066735;5812.854678633068;5853.853653658693;5082.754725092376;5666.781641947001;5719.170720245458;5727.627704199213;5759.7408116634015 02:34:00 INFO - PROCESS | 1644 | |5;yui.html;656.3666062249928;738.9728277694006;764.105995984698;772.9845403091934;781.9100803407609;14435.061720988142;14958;15061.623459413493;15064.020838645507;15074.773878391832;13378.464861405531;13500.18998860069;13510.864891351084;13532;13538.255395953218;784.7881072110526;799.5122974985254;800.2277801921144;800.6797281087582;802.6508468816055;671.5030877150898;797.9401544884128;800.1118758178417;805.6374631415857;805.7542449552884;853.0999795216045;853.3642436384902;855.119227195986;856.327782690588;860.1742327365705;838.4047326398234;843.7215718812777;845.4251109245711;846.238385453091;847.1993965702352;635.0030452190017;636.4081404294004;636.7612145445459;637.3371693438818;638.9808305750835;12136.016517002046;12364.1963272387;12545.184563003468;12548.682388349183;12903.806442903364;11399.3730344831;11504.597339093092;11555.364454954974;11643.24318919276;11686.298822070617;10231.181505479544;10286.125679317267;10395.428251446167;10418.531166097544;10639.255252132354;3363.6972672459597;3406.42090844557;3448.1724386147143;3468.7051600613977;3570.2145527983803;91932;93148;93148.53425732828;94261;94453;8537.871931921027;8615.095414981337;8667.826643467095;8933.15135062168;9093.545322733837;18185;18437.81562184361;18592.907035464737;18627.348042818438;18698.71951920722;7315.963420182865;7529.284717951786;7531.962340188264;7703.9410190334465;7800.9609951951015;3681.9815900920325;4267.850625228102;4321.632661223768;4337.50118736343;4346.461713181376;3314.7679662423398;3447.844846981892;3455.2916652086765;3456.0668619472603;3462.4633181856852;543.1933578635723;629.3549112160073;640.5516138702926;640.7917426836203;649.6297110646885;610.8381278961078;639.45646200729;641.3298103481873;648.4650163615037;666.5933780393956;8037.8392432152195;8372.288355489729;8825.470471771714;8921.197092261727;8930.525369107345;638.4008059810146;644.0789670770843;663.8639078988796;668.2482207516543;672.1463741048841;3611.656892595199;3963.6630886374405;4032.193561287754;4408.25059739845;4502.909941801146;67751.66124169348;91269.08730912738;108402.45798771114;108763.45618271858;115829;57600.847983040105;59660.403395965484;71462;93566.0643393571;98555;73634;75791.62104189444;77914.61042694864;80048;81384;9088.636454541744;9387.10822471864;9408.059194080537;9462.526873656427;9518.524073796145;35670;39999;52284;55060.72469637626;55230;70692;77448;77736.61131694305;78469;79120.81318780108;11642.068634509389;12037.337946413032;12179.147459677737;12330.691732706395;12747.681307967376;13446.193228406328;13607.183568985893;13954.651133721332;14400.135991840523;14546.4908728194;12391.318477483825;12957.611271661865;13021.609351719464;13363.732725345633;13536.187828730308;12169.939150304192;13023.283719395145;13032.869671303544;13633.659158520719;13636;12691.873081269068;12803.551875684307;13136.080474366703;13208.075434719478;13275;10112.393256404639;10395.58417663285;10555.788884222426;10573.577056917944;10640.308379955279;10430.739231519272;10833.566657333618;10974.579870775366;11042.889571104186;11119.555217791198 02:34:00 INFO - PROCESS | 1644 | __end_tp_report 02:34:00 INFO - PROCESS | 1644 | __start_cc_report 02:34:00 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,375 02:34:00 INFO - PROCESS | 1644 | __end_cc_report 02:34:00 INFO - PROCESS | 1644 | __startTimestamp1482921240450__endTimestamp 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:34:00 INFO - PROCESS | 1644 | Number of tests: 6 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5576.25 Median:5784.16 stddev:718.71 (12.4%) stddev-sans-first:678.11 02:34:00 INFO - PROCESS | 1644 | Values: 2461.4 2512.7 3358.2 3361.3 3376.5 4072.8 4870.0 5689.4 5696.1 5700.4 5641.8 5646.2 5657.7 5663.7 5671.2 5152.2 5152.3 5221.6 5240.7 5262.2 3770.5 4794.0 5190.8 5253.6 5267.4 5123.4 5147.3 5203.3 5214.7 5248.8 4962.3 5148.7 5195.0 5221.7 5254.4 4238.3 5141.1 5156.9 5167.9 5183.2 5581.6 5659.5 5672.5 5695.6 5703.8 5970.6 6100.9 6114.2 6133.8 6136.0 6114.1 6186.9 6191.7 6265.7 6306.7 5705.7 5726.2 5737.3 5753.6 5762.7 6145.8 6249.9 6260.3 6270.8 6283.6 5974.4 6084.1 6090.3 6097.2 6126.8 6111.3 6141.5 6163.4 6165.2 6182.6 4223.9 5892.7 5896.1 5896.9 5911.2 5657.4 5959.2 5992.6 6038.4 6076.9 4849.6 4871.7 4907.8 4951.5 4988.5 3780.7 3943.1 4329.6 4407.2 4409.8 5586.5 5667.3 5853.2 5861.2 5885.4 5443.5 5873.6 6125.0 6172.8 6183.5 5758.2 5784.2 5791.9 5800.4 5832.1 5388.0 5399.5 5413.5 5417.9 5418.0 6234.3 6237.5 6256.9 6274.0 6282.1 5078.6 5306.3 5380.9 5659.6 6263.6 5163.7 5736.6 5792.9 6092.7 6329.2 4584.5 5817.2 6126.0 6126.9 6127.1 5231.6 5729.2 6119.4 6235.6 6239.5 5162.3 5939.2 6113.6 6144.5 6145.4 5945.4 6008.8 6016.2 6044.0 6103.1 5797.2 5885.6 5905.8 5948.4 6006.1 4923.3 6112.3 6129.3 6154.2 6205.5 6023.3 6027.2 6032.9 6039.2 6044.3 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:70808.21 Median:40338.20 stddev:156243.24 (387.3%) stddev-sans-first:156592.25 02:34:00 INFO - PROCESS | 1644 | Values: 451.6 463.8 492.6 525.7 584.4 30005.7 38857.6 38883.4 38897.8 38934.0 40318.4 40358.0 40422.8 40470.4 40488.6 9165.0 9386.1 9406.6 9456.3 9513.5 9315.6 9418.1 9429.8 9437.0 9449.1 9363.9 9448.9 9451.4 9477.0 9483.5 9353.0 9423.7 9439.2 9443.6 9450.4 9330.4 9436.9 9446.4 9447.3 9447.4 37480.0 37824.4 37871.0 37886.0 38031.8 3402.3 3439.3 3443.7 3851.7 3910.5 28755.3 29061.3 29459.4 29485.7 29495.3 3168.0 3175.4 3197.9 3208.5 3228.2 484866.0 485455.0 492808.0 625756.0 626900.0 60558.7 61153.0 61237.0 61347.4 61453.7 63536.0 64088.4 64295.0 64327.0 64477.4 13986.4 15558.5 15642.3 15661.1 16136.8 9974.5 12438.8 12478.5 12481.5 12483.2 18184.5 18297.2 18503.0 18528.9 18538.9 6708.9 7502.1 7733.2 7770.4 7820.4 744.7 761.6 866.5 869.8 899.6 28508.9 28718.6 29244.4 29816.9 29898.0 822.4 981.5 989.9 1009.7 1092.4 14350.0 15013.6 15400.7 15518.8 15563.8 655606.0 657868.0 663678.0 666159.0 667565.0 55895.7 56272.7 56325.0 56418.4 56680.0 1203.4 1248.9 1267.2 1314.8 1317.7 23967.8 31355.0 31475.0 32034.0 32040.4 51742.0 52112.0 52227.7 52389.0 52581.0 529494.0 533913.0 534716.0 535781.0 535826.0 25351.7 25527.0 25813.9 25835.2 25958.6 44159.0 44358.3 44415.8 44957.8 45068.5 37660.8 37823.2 37932.5 38085.8 38181.8 29629.0 37384.6 38170.4 38170.8 38203.6 33516.3 36092.0 36838.6 37009.8 38531.6 32217.0 33919.0 34011.5 34343.0 40711.4 27497.5 29291.1 30614.0 30651.2 32361.7 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34343.41 Median:2671.33 stddev:100749.32 (3771.5%) stddev-sans-first:100999.54 02:34:00 INFO - PROCESS | 1644 | Values: 309.6 362.3 368.3 370.1 399.7 18900.5 19862.7 19897.3 19969.5 20177.0 16617.0 19743.0 19869.7 20083.5 20343.9 5135.5 5644.8 5775.3 6075.5 6103.2 4916.7 5041.3 5078.2 6002.6 6016.6 5785.8 5787.9 5957.0 5961.7 5970.9 5095.4 5383.6 5653.3 5817.4 5942.0 4467.8 5033.1 5148.6 5155.4 5168.2 17477.5 20202.5 25221.5 25263.7 25348.1 2261.6 2269.5 2364.4 2620.3 2722.3 14869.9 14954.0 15218.2 15431.5 15506.6 1782.6 1793.9 1834.2 1871.1 1901.6 112505.4 117003.0 117936.4 118283.4 118313.4 9579.7 10648.4 11117.9 11400.3 11529.6 13393.6 13811.0 13816.6 13828.7 13838.4 8300.9 8543.2 8553.7 8674.9 8689.1 8490.3 8583.4 8590.6 8694.6 8728.4 5394.5 5591.0 5933.8 6193.3 6585.3 1566.5 1824.2 1847.1 1849.1 1852.0 887.7 927.9 953.7 958.1 981.2 17400.1 20833.3 21685.6 21759.1 22028.9 760.6 881.8 899.9 903.2 906.1 9931.4 11002.3 11008.1 11677.5 11718.9 598322.0 601785.0 602928.0 615019.0 617782.0 82397.0 114577.0 118333.0 121570.0 125298.0 898.1 974.4 979.9 993.3 1032.2 18321.3 18747.9 19176.3 19196.6 19228.0 46064.5 47348.3 47878.3 48409.8 49035.0 64441.0 64796.7 66878.0 68114.0 68629.0 6521.5 7565.4 7857.2 7905.3 8087.4 12678.2 12851.9 12887.3 13150.5 13327.0 9497.8 10908.5 11373.6 11445.7 11461.0 10801.9 11112.3 11226.4 11394.0 11427.1 10838.5 11281.9 11286.7 11672.5 11852.3 7846.3 7893.8 7936.0 8036.6 8092.0 11176.9 11214.2 11325.5 11344.1 11479.4 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5436.57 Median:5270.67 stddev:2953.02 (56.0%) stddev-sans-first:2945.94 02:34:00 INFO - PROCESS | 1644 | Values: 1428.4 1432.7 1442.1 1587.9 1700.8 7534.6 7840.5 8107.7 8582.5 8584.7 8011.9 8057.8 8381.0 8387.5 8469.2 2339.4 2342.3 2346.2 2350.4 2382.9 2344.6 2380.2 2381.5 2409.8 2410.2 2329.2 2341.4 2350.5 2371.5 2379.3 2233.4 2236.9 2260.5 2261.2 2281.0 1979.0 2359.1 2369.0 2378.4 2379.9 8416.7 8470.0 8513.0 8516.9 8546.8 5093.0 5251.2 5257.3 5258.4 5283.0 3094.1 3301.8 3434.7 3607.0 3619.2 887.5 951.2 996.9 1079.1 1124.8 9794.9 9875.5 11062.7 11150.8 11154.6 4452.6 4458.3 4532.8 4630.7 4641.5 8706.9 9504.9 9590.0 9599.0 9614.0 5211.0 5211.6 5222.5 5232.1 5247.5 4219.3 4249.5 4702.9 4755.9 4787.6 3837.8 3874.9 3947.8 3960.0 3999.8 1276.1 1279.0 1288.3 1294.7 1301.9 794.8 806.2 889.5 898.8 907.5 5599.1 5625.2 5697.5 5858.1 5897.3 943.6 958.4 985.3 1006.7 1025.4 3581.1 3661.3 3665.2 3710.0 3754.0 9043.8 9740.8 9946.6 10238.9 10252.9 7856.5 8995.5 9543.9 10141.3 10179.5 7701.5 7999.8 8009.7 8015.8 8019.7 4976.8 5336.4 5692.0 5845.3 5875.5 9228.7 9338.0 9429.0 9466.0 9483.2 9570.1 9656.3 9693.2 9723.4 9833.8 6755.2 6982.4 7205.2 7259.0 7405.1 6625.9 7258.4 8067.2 8134.4 8176.6 5836.0 7183.0 7280.3 7366.7 7449.4 6875.0 6997.9 7051.8 7144.5 7187.6 6375.7 6763.3 6772.9 6804.3 6861.2 4917.7 4923.1 4937.9 4953.7 4981.9 4901.8 4942.2 4973.8 4983.7 5030.0 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4547.37 Median:5116.68 stddev:1412.20 (27.6%) stddev-sans-first:1379.51 02:34:00 INFO - PROCESS | 1644 | Values: 288.5 295.1 300.3 301.1 302.8 4775.0 4845.1 5183.7 5187.5 5204.8 5096.8 5328.8 5342.7 5345.2 5373.8 3251.5 4071.3 4139.3 4156.9 4170.4 3973.2 4055.7 4133.9 4160.4 4201.8 3384.6 4068.5 4093.9 4114.7 4116.0 3763.8 4094.4 4114.9 4118.9 4147.6 2989.6 3604.9 3618.6 3625.0 3627.4 4826.6 5039.2 5232.2 5254.4 5259.3 4705.1 4706.0 4712.3 4718.2 4727.9 5654.4 5689.6 5734.1 5743.6 5757.8 2985.1 3547.5 3626.8 3750.7 3868.9 5010.7 6029.4 6041.7 6051.7 6096.8 3340.7 3948.8 3966.2 3971.3 3997.3 3892.8 3947.2 4000.6 4007.5 4014.3 4137.9 4303.7 4646.3 4687.1 4699.4 4763.7 5779.7 5809.6 5864.8 5877.0 1191.5 1233.8 1260.6 1282.5 1290.5 758.5 852.6 873.9 880.9 886.9 4621.3 4670.5 4890.1 5047.8 5157.4 4454.6 5052.6 5172.5 5257.5 5648.2 4642.3 4857.3 5073.6 5143.4 5208.7 2823.9 2917.0 2920.0 2946.8 2949.3 5082.1 5691.6 5827.0 5863.8 5864.3 5167.6 5323.4 5666.0 5729.6 5806.8 5552.7 5582.9 5618.5 5829.9 5881.9 4527.8 5526.4 5632.5 5644.0 5711.4 5138.5 5292.7 5429.0 5477.0 5522.2 5390.6 5473.8 5824.9 5830.3 5846.3 3433.8 3498.5 3712.5 3752.6 3769.4 5153.8 5532.7 6040.9 6043.0 6048.8 5610.6 5647.4 5716.3 5735.8 5820.5 5070.7 5136.6 5808.4 5831.8 5834.4 5315.1 5417.8 5621.1 5722.5 5740.9 4655.1 5312.7 5802.7 5812.9 5853.9 5082.8 5666.8 5719.2 5727.6 5759.7 02:34:00 INFO - PROCESS | 1644 | 02:34:00 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19283.34 Median:26413.60 stddev:28106.63 (106.4%) stddev-sans-first:28150.68 02:34:00 INFO - PROCESS | 1644 | Values: 656.4 739.0 764.1 773.0 781.9 14435.1 14958.0 15061.6 15064.0 15074.8 13378.5 13500.2 13510.9 13532.0 13538.3 784.8 799.5 800.2 800.7 802.7 671.5 797.9 800.1 805.6 805.8 853.1 853.4 855.1 856.3 860.2 838.4 843.7 845.4 846.2 847.2 635.0 636.4 636.8 637.3 639.0 12136.0 12364.2 12545.2 12548.7 12903.8 11399.4 11504.6 11555.4 11643.2 11686.3 10231.2 10286.1 10395.4 10418.5 10639.3 3363.7 3406.4 3448.2 3468.7 3570.2 91932.0 93148.0 93148.5 94261.0 94453.0 8537.9 8615.1 8667.8 8933.2 9093.5 18185.0 18437.8 18592.9 18627.3 18698.7 7316.0 7529.3 7532.0 7703.9 7801.0 3682.0 4267.9 4321.6 4337.5 4346.5 3314.8 3447.8 3455.3 3456.1 3462.5 543.2 629.4 640.6 640.8 649.6 610.8 639.5 641.3 648.5 666.6 8037.8 8372.3 8825.5 8921.2 8930.5 638.4 644.1 663.9 668.2 672.1 3611.7 3963.7 4032.2 4408.3 4502.9 67751.7 91269.1 108402.5 108763.5 115829.0 57600.8 59660.4 71462.0 93566.1 98555.0 73634.0 75791.6 77914.6 80048.0 81384.0 9088.6 9387.1 9408.1 9462.5 9518.5 35670.0 39999.0 52284.0 55060.7 55230.0 70692.0 77448.0 77736.6 78469.0 79120.8 11642.1 12037.3 12179.1 12330.7 12747.7 13446.2 13607.2 13954.7 14400.1 14546.5 12391.3 12957.6 13021.6 13363.7 13536.2 12169.9 13023.3 13032.9 13633.7 13636.0 12691.9 12803.6 13136.1 13208.1 13275.0 10112.4 10395.6 10555.8 10573.6 10640.3 10430.7 10833.6 10974.6 11042.9 11119.6 02:34:00 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:34:00 INFO - PROCESS | 1644 | 02:34:01 INFO - TEST-INFO | 1644: exit 0 02:34:01 INFO - TEST-OK | dromaeo_css | took 1116703ms 02:34:01 INFO - TEST-START | kraken 02:34:01 INFO - operating with platform_type : mac_ 02:34:01 INFO - Initialising browser for kraken test... 02:34:01 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmphMWuKv/profile http://localhost:49247/getInfo.html) 02:34:04 INFO - PROCESS | 1652 | 2016-12-28 02:34:04.034 plugin-container[1654:10755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 02:34:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:34:04 INFO - PROCESS | 1652 | 2016-12-28 02:34:04.035 plugin-container[1654:10755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 02:34:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:34:04 INFO - PROCESS | 1652 | 2016-12-28 02:34:04.035 plugin-container[1654:10755] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:34:04 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:34:04 INFO - PROCESS | 1652 | colorDepth:24 02:34:04 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:34:04 INFO - PROCESS | 1652 | __metrics 02:34:04 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:34:05 INFO - TEST-INFO | 1652: exit 0 02:34:05 INFO - Browser initialized. 02:34:05 INFO - Running cycle 1/1 for kraken test... 02:34:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmphMWuKv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:34:07 INFO - PROCESS | 1655 | 2016-12-28 02:34:07.784 plugin-container[1656:10880] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 02:34:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:34:07 INFO - PROCESS | 1655 | 2016-12-28 02:34:07.785 plugin-container[1656:10880] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 02:34:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:34:07 INFO - PROCESS | 1655 | 2016-12-28 02:34:07.785 plugin-container[1656:10880] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:34:17 INFO - PROCESS | 1655 | RSS: Main: 229740544 02:34:17 INFO - PROCESS | 1655 | 02:34:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 02:34:21 INFO - PROCESS | 1655 | RSS: Main: 241623040 02:34:21 INFO - PROCESS | 1655 | 02:34:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 02:34:24 INFO - PROCESS | 1655 | RSS: Main: 249229312 02:34:24 INFO - PROCESS | 1655 | 02:34:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 02:34:28 INFO - PROCESS | 1655 | RSS: Main: 252911616 02:34:28 INFO - PROCESS | 1655 | 02:34:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 02:34:30 INFO - PROCESS | 1655 | RSS: Main: 254312448 02:34:30 INFO - PROCESS | 1655 | 02:34:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:34:33 INFO - PROCESS | 1655 | RSS: Main: 254312448 02:34:33 INFO - PROCESS | 1655 | 02:34:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 02:34:36 INFO - PROCESS | 1655 | RSS: Main: 258412544 02:34:36 INFO - PROCESS | 1655 | 02:34:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 02:34:40 INFO - PROCESS | 1655 | RSS: Main: 259252224 02:34:40 INFO - PROCESS | 1655 | 02:34:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 02:34:44 INFO - PROCESS | 1655 | RSS: Main: 258330624 02:34:44 INFO - PROCESS | 1655 | 02:34:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:34:46 INFO - PROCESS | 1655 | RSS: Main: 257740800 02:34:46 INFO - PROCESS | 1655 | 02:34:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:34:48 INFO - PROCESS | 1655 | RSS: Main: 257802240 02:34:48 INFO - PROCESS | 1655 | 02:34:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:34:51 INFO - PROCESS | 1655 | RSS: Main: 257814528 02:34:51 INFO - PROCESS | 1655 | 02:34:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:34:55 INFO - PROCESS | 1655 | RSS: Main: 257904640 02:34:55 INFO - PROCESS | 1655 | 02:34:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:34:58 INFO - PROCESS | 1655 | RSS: Main: 258236416 02:34:58 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 02:35:00 INFO - PROCESS | 1655 | RSS: Main: 260128768 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | __start_tp_report 02:35:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:35:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:35:00 INFO - PROCESS | 1655 | |i|pagename|runs| 02:35:00 INFO - PROCESS | 1655 | |0;ai-astar;112;117;120;119;120;117;120;125;107;107 02:35:00 INFO - PROCESS | 1655 | |1;audio-beat-detection;146;151;96;175;97;101;123;144;178;120 02:35:00 INFO - PROCESS | 1655 | |2;audio-dft;200;195;205;247;200;195;193;197;198;196 02:35:00 INFO - PROCESS | 1655 | |3;audio-fft;80;58;64;98;51;82;51;59;97;81 02:35:00 INFO - PROCESS | 1655 | |4;audio-oscillator;82;69;90;71;68;71;86;69;58;101 02:35:00 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;84;129;133;86;91;85;79;131;135;85 02:35:00 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;97;95;113;154;125;96;95;95;95 02:35:00 INFO - PROCESS | 1655 | |7;imaging-desaturate;96;131;86;92;90;89;90;93;89;84 02:35:00 INFO - PROCESS | 1655 | |8;json-parse-financial;57;57;56;57;61;62;60;62;60;61 02:35:00 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;72;69;74;85;78;71;71;72;76;73 02:35:00 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;84;87;88;83;86;86;87;84;74 02:35:00 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;143;138;137;140;144;144;143;166;207;150 02:35:00 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;171;199;169;167;169;166;169;167;167;165 02:35:00 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;70;64;64;62;68;67;69;70;68;77 02:35:00 INFO - PROCESS | 1655 | __end_tp_report 02:35:00 INFO - PROCESS | 1655 | __start_cc_report 02:35:00 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,994 02:35:00 INFO - PROCESS | 1655 | __end_cc_report 02:35:00 INFO - PROCESS | 1655 | __startTimestamp1482921300567__endTimestamp 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:35:00 INFO - PROCESS | 1655 | Number of tests: 14 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:116.40 Median:119.50 stddev:5.93 (5.0%) stddev-sans-first:6.07 02:35:00 INFO - PROCESS | 1655 | Values: 112.0 117.0 120.0 119.0 120.0 117.0 120.0 125.0 107.0 107.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:163.00 stddev:30.48 (18.7%) stddev-sans-first:31.97 02:35:00 INFO - PROCESS | 1655 | Values: 146.0 151.0 96.0 175.0 97.0 101.0 123.0 144.0 178.0 120.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:202.60 Median:199.00 stddev:15.97 (8.0%) stddev-sans-first:16.91 02:35:00 INFO - PROCESS | 1655 | Values: 200.0 195.0 205.0 247.0 200.0 195.0 193.0 197.0 198.0 196.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:72.10 Median:80.50 stddev:17.82 (22.1%) stddev-sans-first:18.67 02:35:00 INFO - PROCESS | 1655 | Values: 80.0 58.0 64.0 98.0 51.0 82.0 51.0 59.0 97.0 81.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:71.00 stddev:12.87 (18.1%) stddev-sans-first:13.49 02:35:00 INFO - PROCESS | 1655 | Values: 82.0 69.0 90.0 71.0 68.0 71.0 86.0 69.0 58.0 101.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:103.80 Median:84.50 stddev:24.48 (29.0%) stddev-sans-first:24.90 02:35:00 INFO - PROCESS | 1655 | Values: 84.0 129.0 133.0 86.0 91.0 85.0 79.0 131.0 135.0 85.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.00 stddev:19.72 (20.8%) stddev-sans-first:20.51 02:35:00 INFO - PROCESS | 1655 | Values: 95.0 97.0 95.0 113.0 154.0 125.0 96.0 95.0 95.0 95.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:90.00 stddev:13.43 (14.9%) stddev-sans-first:14.23 02:35:00 INFO - PROCESS | 1655 | Values: 96.0 131.0 86.0 92.0 90.0 89.0 90.0 93.0 89.0 84.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:60.50 stddev:2.31 (3.8%) stddev-sans-first:2.30 02:35:00 INFO - PROCESS | 1655 | Values: 57.0 57.0 56.0 57.0 61.0 62.0 60.0 62.0 60.0 61.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:73.50 stddev:4.63 (6.3%) stddev-sans-first:4.85 02:35:00 INFO - PROCESS | 1655 | Values: 72.0 69.0 74.0 85.0 78.0 71.0 71.0 72.0 76.0 73.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:86.00 stddev:3.98 (4.6%) stddev-sans-first:4.21 02:35:00 INFO - PROCESS | 1655 | Values: 85.0 84.0 87.0 88.0 83.0 86.0 86.0 87.0 84.0 74.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:144.00 stddev:21.27 (14.8%) stddev-sans-first:22.36 02:35:00 INFO - PROCESS | 1655 | Values: 143.0 138.0 137.0 140.0 144.0 144.0 143.0 166.0 207.0 150.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:169.00 stddev:10.03 (5.9%) stddev-sans-first:10.64 02:35:00 INFO - PROCESS | 1655 | Values: 171.0 199.0 169.0 167.0 169.0 166.0 169.0 167.0 167.0 165.0 02:35:00 INFO - PROCESS | 1655 | 02:35:00 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:4.20 (6.1%) stddev-sans-first:4.39 02:35:00 INFO - PROCESS | 1655 | Values: 70.0 64.0 64.0 62.0 68.0 67.0 69.0 70.0 68.0 77.0 02:35:00 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:35:00 INFO - PROCESS | 1655 | 02:35:01 INFO - TEST-INFO | 1655: exit 0 02:35:01 INFO - TEST-OK | kraken | took 60650ms 02:35:01 INFO - SUITE-END | took 1177s 02:35:02 INFO - Completed test suite (00:19:37) 02:35:02 INFO - javascript benchmark 02:35:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7118.904438843836, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2461.4461746107118, 2512.6733524641804, 3358.244395011123, 3361.26052268501, 3376.4935259711046, 4072.755634661922, 4869.975650121749, 5689.402612725661, 5696.145578163268, 5700.429957004298, 5641.830745077644, 5646.153077038446, 5657.745401456934, 5663.7168141592965, 5671.177679236508, 5152.227165925123, 5152.304438900756, 5221.556167725748, 5240.685558866461, 5262.184361423985, 3770.490983717204, 4794.0, 5190.844274671747, 5253.60597955153, 5267.3942496613, 5123.4108077571, 5147.305113809644, 5203.2975548301065, 5214.661047031931, 5248.790048398078, 4962.255661650745, 5148.74256287186, 5195.0, 5221.73891305437, 5254.369475662907, 4238.258304796648, 5141.100307446182, 5156.948430515685, 5167.948320516784, 5183.248428977777, 5581.609287349887, 5659.547236221092, 5672.4611161939965, 5695.601307908448, 5703.828885133453, 5970.55220858433, 6100.944524685187, 6114.2365411686305, 6133.846653833645, 6136.028952951454, 6114.145302971524, 6186.876262474771, 6191.721372538246, 6265.686715664198, 6306.747730090791, 5705.657660540381, 5726.226959360475, 5737.254156959611, 5753.597248192628, 5762.74067666956, 6145.770845830852, 6249.9062514062325, 6260.311365749764, 6270.811875643738, 6283.622982621057, 5974.3726908674835, 6084.117802918552, 6090.33006369299, 6097.207363042776, 6126.754929802847, 6111.318268824403, 6141.5473781508945, 6163.445289923929, 6165.229346331708, 6182.567220294536, 4223.915521689549, 5892.6759028253, 5896.086106653475, 5896.911546326808, 5911.201987731602, 5657.377688454267, 5959.165716799652, 5992.580519363602, 6038.365971573009, 6076.939230607726, 4849.563539281411, 4871.658983871095, 4907.779149938261, 4951.455339912607, 4988.501149884983, 3780.716446266486, 3943.132510847609, 4329.567043295645, 4407.184670835842, 4409.779511024436, 5586.497215250647, 5667.348254950651, 5853.209816674779, 5861.17943487912, 5885.382034886361, 5443.455654434604, 5873.6490607160595, 6124.960759790047, 6172.814815555541, 6183.505319574423, 5758.222639943554, 5784.161296612052, 5791.942080579224, 5800.390958949334, 5832.096025116114, 5387.973060134753, 5399.460053994648, 5413.539849112791, 5417.891642167135, 5417.972910135424, 6234.314225435108, 6237.532185086227, 6256.906146407808, 6274.027525733519, 6282.120503129474, 5078.56832169255, 5306.310179676771, 5380.865478363072, 5659.613394718156, 6263.561550691408, 5163.690178589297, 5736.627119237236, 5792.9283082629845, 6092.725827337869, 6329.240491141092, 4584.541545845389, 5817.156512305776, 6126.029144987462, 6126.884121540821, 6127.142200091902, 5231.607629427732, 5729.169270455677, 6119.388061194023, 6235.563510554218, 6239.532035097477, 5162.277281180713, 5939.227900372924, 6113.633182009094, 6144.539159562962, 6145.385461453998, 5945.375735547878, 6008.849778755566, 6016.187814644967, 6043.969780151071, 6103.115048318058, 5797.2173756542325, 5885.646861188342, 5905.822825315247, 5948.405159484017, 6006.09908513709, 4923.310736496965, 6112.321110680095, 6129.2644882612585, 6154.1999540059505, 6205.503559715122, 6023.3073196582955, 6027.186329845415, 6032.879342413039, 6039.214902062704, 6044.274687037408], "value": 5529.028633977551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.6409454483686, 463.77738685431, 492.6379111353155, 525.6950968438306, 584.4068270705237, 30005.699943000564, 38857.611423885755, 38883.41674874876, 38897.8330650081, 38933.99999999996, 40318.39522407159, 40358.0, 40422.78731638048, 40470.392944105864, 40488.595114048854, 9164.954175229132, 9386.108319709616, 9406.576704048335, 9456.338056336059, 9513.524323783819, 9315.580798864034, 9418.058194180561, 9429.774129363173, 9437.009114042996, 9449.05509449053, 9363.906360936371, 9448.905510944905, 9451.432914025181, 9476.952615236914, 9483.525823708822, 9352.953235233814, 9423.717288481357, 9439.19766819821, 9443.622255109787, 9450.43297402158, 9330.393524420959, 9436.905630943671, 9446.433214007113, 9447.338686291894, 9447.433154010712, 37480.0, 37824.43263351052, 37871.0, 37886.0, 38031.80984095062, 3402.2515046689687, 3439.329330780499, 3443.6728510791436, 3851.6726078283377, 3910.4720862683744, 28755.28111797222, 29061.273468163254, 29459.410811783644, 29485.705142948726, 29495.263425275694, 3167.984160079208, 3175.3966746318124, 3197.888073917425, 3208.5347624594538, 3228.1929517620597, 484866.0, 485455.0, 492808.0, 625756.0, 626899.9999999955, 60558.69720651369, 61153.0, 61237.0, 61347.38652613506, 61453.69273153606, 63536.0, 64088.35911640824, 64295.0, 64327.0, 64477.35522644714, 13986.370613322424, 15558.455454059054, 15642.296096675676, 15661.138637375054, 16136.838631613533, 9974.451405172786, 12438.751224975449, 12478.5008599655, 12481.500739970299, 12483.188592741442, 18184.545386365447, 18297.176627051813, 18503.0, 18528.907355463136, 18538.907305463657, 6708.901235061388, 7502.099748030271, 7733.188015258429, 7770.378369730408, 7820.413468989848, 744.728174216418, 761.5963539324056, 866.4801119328353, 869.7651634058768, 899.6131663384676, 28508.857455713005, 28718.569221461694, 29244.415111698072, 29816.850915745283, 29898.0, 822.3873214455269, 981.4945303168873, 989.8564708117283, 1009.6814707132975, 1092.4100985467917, 14350.0, 15013.624659383384, 15400.663821716544, 15518.844811551966, 15563.766543501857, 655606.0, 657868.0, 663678.0, 666159.0, 667565.0, 55895.72052139713, 56272.71863640737, 56325.0, 56418.435815641315, 56680.0, 1203.4163430736087, 1248.8563815161372, 1267.2143271171933, 1314.7764879970428, 1317.6639956810952, 23967.76032239725, 31355.0, 31475.0, 32034.03897883067, 32040.359192815547, 51742.0, 52112.0, 52227.69430764261, 52389.0, 52581.0, 529494.0, 533913.0, 534716.0, 535781.0, 535826.0, 25351.746482534938, 25527.0, 25813.870930645226, 25835.22494325173, 25958.610620840325, 44159.0, 44358.3346249813, 44415.77792111019, 44957.775211123735, 45068.54931450643, 37660.811695941346, 37823.243535129695, 37932.4702103607, 38085.80957095197, 38181.80909095548, 29629.0, 37384.626153739206, 38170.427443588924, 38170.809145954096, 38203.617963820005, 33516.329673406886, 36092.0, 36838.63161368352, 37009.81495092615, 38531.6146838528, 32217.0, 33919.0, 34011.4898276531, 34342.969710908714, 40711.38932916068, 27497.45005099927, 29291.12126636119, 30614.0, 30651.2337191572, 32361.676383235867], "value": 19609.824674310352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.5928853557572, 362.31160794490484, 368.30574367317615, 370.06373874098523, 399.73817149766916, 18900.527486812836, 19862.708923919952, 19897.30359437418, 19969.500762480944, 20177.0, 16617.00297982122, 19743.0, 19869.708468949546, 20083.497912552193, 20343.898280508613, 5135.486451354854, 5644.8306550803545, 5775.306963164426, 6075.513958883279, 6103.206583144185, 4916.655834091615, 5041.294218809369, 5078.162103252959, 6002.609830361012, 6016.578839481238, 5785.797497087603, 5787.913181302284, 5957.0, 5961.672108034057, 5970.925233457976, 5095.363079615048, 5383.596230282706, 5653.349864765563, 5817.389174136718, 5941.970290148543, 4467.7542735149555, 5033.094043072246, 5148.601703574129, 5155.381354237478, 5168.22476628505, 17477.475675729747, 20202.49493762653, 25221.49557008868, 25263.747362526323, 25348.11281605135, 2261.6268315727884, 2269.5347453771938, 2364.408897775556, 2620.344913771557, 2722.3194201449637, 14869.851301486955, 14954.0, 15218.239088045553, 15431.459898903597, 15506.61233469161, 1782.643471305744, 1793.9103044847836, 1834.1562881074722, 1871.1392759330724, 1901.610169915164, 112505.43747281293, 117003.0, 117936.41031794871, 118283.40858295739, 118313.40843295728, 9579.712608621752, 10648.361098334124, 11117.944410277896, 11400.315981041163, 11529.596464123882, 13393.59819205406, 13811.033227673966, 13816.585502434944, 13828.723425531432, 13838.377273022737, 8300.916990830136, 8543.188397102265, 8553.700620478254, 8674.872266605427, 8689.131086891386, 8490.27832634233, 8583.356248281403, 8590.570471476527, 8694.608742606599, 8728.389012769172, 5394.514493695508, 5590.97204513983, 5933.792317268875, 6193.349698281706, 6585.341465853472, 1566.498720409458, 1824.224704500582, 1847.131848031423, 1849.1309084730156, 1851.9628408750266, 887.6981826179131, 927.8979312275644, 953.7329547726509, 958.1328897347911, 981.1562056631292, 17400.129993500526, 20833.270835520685, 21685.566288674137, 21759.129634814748, 22028.88985555062, 760.5626764610371, 881.8192270584515, 899.8875140607424, 903.2277402820494, 906.063155588965, 9931.404115752934, 11002.284851484628, 11008.064314533354, 11677.474513646906, 11718.88281117195, 598322.0, 601785.0, 602928.0, 615019.0, 617782.0, 82397.0, 114577.0, 118333.0, 121570.0, 125298.0, 898.1377877237794, 974.4104816586005, 979.9216062715109, 993.2947607198826, 1032.2258306270298, 18321.267149313877, 18747.90626046861, 19176.328828490936, 19196.61606767829, 19228.0, 46064.53935460602, 47348.2897756527, 47878.28182577195, 48409.75795121002, 49035.0, 64441.0, 64796.704065917475, 66878.0, 68114.0, 68629.0, 6521.543491955707, 7565.394768418623, 7857.174996625272, 7905.288524032865, 8087.353011758927, 12678.161666558482, 12851.871481285067, 12887.29119898377, 13150.539731109748, 13327.0, 9497.810043798947, 10908.509117089909, 11373.601923932623, 11445.713857153638, 11461.0, 10801.945990269998, 11112.277701949348, 11226.438678066228, 11394.0, 11427.085833133164, 10838.458077095956, 11281.887181128412, 11286.717832054264, 11672.533098675958, 11852.288862668267, 7846.293833555007, 7893.8159276109045, 7935.960320198593, 8036.598170091589, 8092.0, 11176.888231117584, 11214.215004949901, 11325.490352933974, 11344.149188810707, 11479.36863472517], "value": 9468.472542465459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.4072110074312, 1432.7492688779462, 1442.1491320121127, 1587.9122800881398, 1700.7533907583409, 7534.6232688365635, 7840.490368126068, 8107.6756929722815, 8582.527960962145, 8584.69953551625, 8011.919880801205, 8057.838843223132, 8381.0, 8387.538685372301, 8469.153084691528, 2339.3566769138497, 2342.3090188394463, 2346.2022912209845, 2350.3888988862846, 2382.8570285782907, 2344.6014177589764, 2380.238323736405, 2381.535600557892, 2409.8072154227707, 2410.216679579135, 2329.1964272326104, 2341.356127065054, 2350.4828937633692, 2371.5019845832394, 2379.286214135761, 2233.396982814631, 2236.8769717665523, 2260.480089579398, 2261.208576998054, 2280.9543809123725, 1978.9604207915904, 2359.091749676387, 2368.9694982682418, 2378.4291769975252, 2379.8810059496955, 8416.747497575083, 8469.983601967742, 8513.0, 8516.8722469163, 8546.829063418696, 5093.0, 5251.238570407269, 5257.290265814105, 5258.3689957205, 5282.97358513205, 3094.133642580081, 3301.834908254578, 3434.6737059979264, 3607.0080727799805, 3619.1675914539805, 887.4719541872576, 951.2247518272586, 996.8853581838109, 1079.0504356166523, 1124.831275308702, 9794.853077203848, 9875.456849873182, 11062.668119956414, 11150.776984460346, 11154.60958866444, 4452.5529203009155, 4458.264386376277, 4532.773361331921, 4630.74530900797, 4641.489436162019, 8706.912930870736, 9504.90495095054, 9590.0, 9599.0, 9613.951930240444, 5211.0, 5211.5830733541225, 5222.503862133091, 5232.084385232569, 5247.501487358695, 4219.346001369792, 4249.46881639795, 4702.882427939329, 4755.881102972384, 4787.569118779326, 3837.7505462144873, 3874.85691720943, 3947.842086316515, 3959.9604003960167, 3999.8200080996426, 1276.0875973678865, 1278.9680257993625, 1288.2721262486853, 1294.6763309172707, 1301.8828305452555, 794.7774623105539, 806.1736719862188, 889.5107690770051, 898.820235952799, 907.4827348411342, 5599.132134519156, 5625.184348269559, 5697.4587414195585, 5858.091995740667, 5897.32180799205, 943.6225509796136, 958.4153666263574, 985.3398223190479, 1006.6778630838062, 1025.410389026313, 3581.1405262737276, 3661.3409586274192, 3665.193657395369, 3710.0353907984118, 3753.981230093832, 9043.773905652411, 9740.805183896282, 9946.602135914627, 10238.897611023944, 10252.948735256276, 7856.489328193649, 8995.505247211466, 9543.904560954441, 10141.340812847142, 10179.54192061359, 7701.537907725442, 7999.800004999805, 8009.719659811879, 8015.7595272141925, 8019.679212831421, 4976.751162441936, 5336.412994570516, 5692.0, 5845.269341332333, 5875.529957603467, 9228.676996305097, 9338.019507951567, 9429.00995395501, 9466.0, 9483.241340692866, 9570.090841370058, 9656.275779316438, 9693.22454203648, 9723.367981081206, 9833.803323933624, 6755.189377274562, 6982.371586557234, 7205.207427183111, 7258.963705181441, 7405.148407933157, 6625.867482650417, 7258.419326453976, 8067.193280671886, 8134.430589858653, 8176.550289734122, 5835.970820146042, 7183.0, 7280.3083707047745, 7366.7053317866685, 7449.404047676065, 6875.037494750839, 6997.860042799217, 7051.788446346618, 7144.499885007999, 7187.5687458752645, 6375.681215939092, 6763.2898545652, 6772.864542709217, 6804.319568043156, 6861.210960739579, 4917.729524876167, 4923.064617722621, 4937.901241975069, 4953.702777833198, 4981.850544483671, 4901.779419926041, 4942.1598328285, 4973.776180071978, 4983.725895075806, 5030.0], "value": 4418.377486983064, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.5311369025334, 295.0572919522128, 300.29730430791955, 301.117725065558, 302.84403532180926, 4775.0, 4845.127876982142, 5183.740812959351, 5187.533122019013, 5204.828913494466, 5096.77064532096, 5328.813491527797, 5342.679439233639, 5345.1982202669615, 5373.785048598061, 3251.463508521091, 4071.307877660797, 4139.337705967046, 4156.91686166278, 4170.353595192735, 3973.1854969731135, 4055.655269302098, 4133.9173216535655, 4160.39674247234, 4201.831926722943, 3384.627690953993, 4068.5321188063454, 4093.9181216375655, 4114.732542384751, 4115.991582062399, 3763.7929913854728, 4094.406311084891, 4114.917701645951, 4118.938215926763, 4147.605977432139, 2989.6412430508262, 3604.9098772530633, 3618.6019537850816, 3624.963750362489, 3627.4014787560022, 4826.600285917079, 5039.193729003378, 5232.189010703347, 5254.395744489394, 5259.2899958505495, 4705.129551033036, 4706.0, 4712.32712387101, 4718.245080787057, 4727.905441891177, 5654.406287339812, 5689.573282003825, 5734.139879018139, 5743.569232307594, 5757.783253447453, 2985.134311049793, 3547.5210846535647, 3626.8186590670625, 3750.7374483786148, 3868.883933482, 5010.69935803853, 6029.366916473751, 6041.7010342776075, 6051.727672254715, 6096.756129754849, 3340.6993370596765, 3948.822302996372, 3966.186931678999, 3971.339202734175, 3997.300472417315, 3892.766434013968, 3947.1513624570857, 4000.6199411056486, 4007.4771586796833, 4014.297497937849, 4137.91724165515, 4303.655707543389, 4646.326282688991, 4687.1328804171335, 4699.436067671902, 4763.666543341933, 5779.739911703899, 5809.60138319088, 5864.765409383662, 5877.030290002187, 1191.53530123252, 1233.8396008518835, 1260.6322140477685, 1282.5318758653211, 1290.4709069281737, 758.4993904023429, 852.6400391375746, 873.912608739121, 880.9471431713989, 886.9157430044264, 4621.32990716351, 4670.462896766848, 4890.139268527951, 5047.848564543069, 5157.406898206679, 4454.57681520255, 5052.621053421009, 5172.456892026358, 5257.526822585985, 5648.237487939158, 4642.303654451859, 4857.295692124622, 5073.619478539124, 5143.354126679502, 5208.661437006583, 2823.8729257183477, 2916.9708302917124, 2919.9708002920124, 2946.764258859286, 2949.336399310138, 5082.110630639623, 5691.630044047207, 5826.950269562613, 5863.7947671832135, 5864.266966629171, 5167.612429067835, 5323.441038690882, 5666.0, 5729.598928074995, 5806.82579522615, 5552.666839989613, 5582.944170558324, 5618.539179813278, 5829.854253643693, 5881.882362352814, 4527.818887244605, 5526.364468086222, 5632.493075623213, 5643.971780141237, 5711.428857114255, 5138.537531622172, 5292.682439053669, 5428.995635807389, 5477.029153863417, 5522.171674248895, 5390.595705322038, 5473.808416705396, 5824.94175058244, 5830.271216097988, 5846.269216347957, 3433.759636825398, 3498.5276987607367, 3712.498812660256, 3752.5684546276434, 3769.3780526212868, 5153.793848246178, 5532.723363831873, 6040.879182416415, 6042.969785151046, 6048.818535443944, 5610.607257491936, 5647.350554686263, 5716.314042314949, 5735.827925162252, 5820.476157145878, 5070.670406423718, 5136.5858046717985, 5808.36108028125, 5831.79588714393, 5834.416558344132, 5315.149576067811, 5417.783288668409, 5621.072523033654, 5722.542196624343, 5740.942590574041, 4655.11552804966, 5312.654677445954, 5802.7098645066735, 5812.854678633068, 5853.853653658693, 5082.754725092376, 5666.781641947001, 5719.170720245458, 5727.627704199213, 5759.7408116634015], "value": 4087.403575053594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3666062249928, 738.9728277694006, 764.105995984698, 772.9845403091934, 781.9100803407609, 14435.061720988142, 14958.0, 15061.623459413493, 15064.020838645507, 15074.773878391832, 13378.464861405531, 13500.18998860069, 13510.864891351084, 13532.0, 13538.255395953218, 784.7881072110526, 799.5122974985254, 800.2277801921144, 800.6797281087582, 802.6508468816055, 671.5030877150898, 797.9401544884128, 800.1118758178417, 805.6374631415857, 805.7542449552884, 853.0999795216045, 853.3642436384902, 855.119227195986, 856.327782690588, 860.1742327365705, 838.4047326398234, 843.7215718812777, 845.4251109245711, 846.238385453091, 847.1993965702352, 635.0030452190017, 636.4081404294004, 636.7612145445459, 637.3371693438818, 638.9808305750835, 12136.016517002046, 12364.1963272387, 12545.184563003468, 12548.682388349183, 12903.806442903364, 11399.3730344831, 11504.597339093092, 11555.364454954974, 11643.24318919276, 11686.298822070617, 10231.181505479544, 10286.125679317267, 10395.428251446167, 10418.531166097544, 10639.255252132354, 3363.6972672459597, 3406.42090844557, 3448.1724386147143, 3468.7051600613977, 3570.2145527983803, 91932.0, 93148.0, 93148.53425732828, 94261.0, 94453.0, 8537.871931921027, 8615.095414981337, 8667.826643467095, 8933.15135062168, 9093.545322733837, 18185.0, 18437.81562184361, 18592.907035464737, 18627.348042818438, 18698.71951920722, 7315.963420182865, 7529.284717951786, 7531.962340188264, 7703.9410190334465, 7800.9609951951015, 3681.9815900920325, 4267.850625228102, 4321.632661223768, 4337.50118736343, 4346.461713181376, 3314.7679662423398, 3447.844846981892, 3455.2916652086765, 3456.0668619472603, 3462.4633181856852, 543.1933578635723, 629.3549112160073, 640.5516138702926, 640.7917426836203, 649.6297110646885, 610.8381278961078, 639.45646200729, 641.3298103481873, 648.4650163615037, 666.5933780393956, 8037.8392432152195, 8372.288355489729, 8825.470471771714, 8921.197092261727, 8930.525369107345, 638.4008059810146, 644.0789670770843, 663.8639078988796, 668.2482207516543, 672.1463741048841, 3611.656892595199, 3963.6630886374405, 4032.193561287754, 4408.25059739845, 4502.909941801146, 67751.66124169348, 91269.08730912738, 108402.45798771114, 108763.45618271858, 115829.0, 57600.847983040105, 59660.403395965484, 71462.0, 93566.0643393571, 98555.0, 73634.0, 75791.62104189444, 77914.61042694864, 80048.0, 81384.0, 9088.636454541744, 9387.10822471864, 9408.059194080537, 9462.526873656427, 9518.524073796145, 35670.0, 39999.0, 52284.0, 55060.72469637626, 55230.0, 70692.0, 77448.0, 77736.61131694305, 78469.0, 79120.81318780108, 11642.068634509389, 12037.337946413032, 12179.147459677737, 12330.691732706395, 12747.681307967376, 13446.193228406328, 13607.183568985893, 13954.651133721332, 14400.135991840523, 14546.4908728194, 12391.318477483825, 12957.611271661865, 13021.609351719464, 13363.732725345633, 13536.187828730308, 12169.939150304192, 13023.283719395145, 13032.869671303544, 13633.659158520719, 13636.0, 12691.873081269068, 12803.551875684307, 13136.080474366703, 13208.075434719478, 13275.0, 10112.393256404639, 10395.58417663285, 10555.788884222426, 10573.577056917944, 10640.308379955279, 10430.739231519272, 10833.566657333618, 10974.579870775366, 11042.889571104186, 11119.555217791198], "value": 7019.711122787456, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 120.0, 119.0, 120.0, 117.0, 120.0, 125.0, 107.0, 107.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 151.0, 96.0, 175.0, 97.0, 101.0, 123.0, 144.0, 178.0, 120.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 195.0, 205.0, 247.0, 200.0, 195.0, 193.0, 197.0, 198.0, 196.0], "value": 202.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 64.0, 98.0, 51.0, 82.0, 51.0, 59.0, 97.0, 81.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 90.0, 71.0, 68.0, 71.0, 86.0, 69.0, 58.0, 101.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 129.0, 133.0, 86.0, 91.0, 85.0, 79.0, 131.0, 135.0, 85.0], "value": 103.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 113.0, 154.0, 125.0, 96.0, 95.0, 95.0, 95.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 131.0, 86.0, 92.0, 90.0, 89.0, 90.0, 93.0, 89.0, 84.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 57.0, 61.0, 62.0, 60.0, 62.0, 60.0, 61.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 74.0, 85.0, 78.0, 71.0, 71.0, 72.0, 76.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 88.0, 83.0, 86.0, 86.0, 87.0, 84.0, 74.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 137.0, 140.0, 144.0, 144.0, 143.0, 166.0, 207.0, 150.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 199.0, 169.0, 167.0, 169.0, 166.0, 169.0, 167.0, 167.0, 165.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 64.0, 64.0, 62.0, 68.0, 67.0, 69.0, 70.0, 68.0, 77.0], "value": 67.9, "unit": "score"}]}]} 02:35:02 INFO - Return code: 0 02:35:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:35:02 ERROR - # TBPL SUCCESS # 02:35:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:35:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:35:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:35:02 INFO - 2016-12-28 02:35:02.365 screenresolution[1660:11264] starting screenresolution argv=screenresolution get 02:35:02 INFO - 2016-12-28 02:35:02.368 screenresolution[1660:11264] Display 0: 1600x1200x32@60 02:35:02 INFO - 2016-12-28 02:35:02.374 screenresolution[1661:11267] starting screenresolution argv=screenresolution list 02:35:02 INFO - Available Modes on Display 0 02:35:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:35:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:35:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:35:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:35:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:35:02 INFO - Intel Iris: 02:35:02 INFO - Chipset Model: Intel Iris 02:35:02 INFO - Type: GPU 02:35:02 INFO - Bus: Built-In 02:35:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:35:02 INFO - Vendor: Intel (0x8086) 02:35:02 INFO - Device ID: 0x0a2e 02:35:02 INFO - Revision ID: 0x0009 02:35:02 INFO - Displays: 02:35:02 INFO - Display: 02:35:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:35:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:35:02 INFO - Display Serial Number: 1600x1200 60 02:35:02 INFO - Main Display: Yes 02:35:02 INFO - Mirror: Off 02:35:02 INFO - Online: Yes 02:35:02 INFO - Rotation: Supported 02:35:02 INFO - Return code: 0 02:35:02 INFO - Running post-action listener: _resource_record_post_action 02:35:02 INFO - [mozharness: 2016-12-28 10:35:02.609658Z] Finished run-tests step (success) 02:35:02 INFO - Running post-run listener: _resource_record_post_run 02:35:02 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 33697792; Write bytes: 662196736; Read time: 202; Write time: 1948 02:35:02 INFO - TinderboxPrint: CPU usage
54.2% 02:35:02 INFO - TinderboxPrint: I/O read bytes / time
33,697,792 / 202 02:35:02 INFO - TinderboxPrint: I/O write bytes / time
662,196,736 / 1,948 02:35:02 INFO - TinderboxPrint: CPU idle
2,215.8 (45.8%) 02:35:02 INFO - TinderboxPrint: CPU system
971.9 (20.1%) 02:35:02 INFO - TinderboxPrint: CPU user
1,650.0 (34.1%) 02:35:02 INFO - TinderboxPrint: Swap in / out
378,724,352 / 0 02:35:02 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345121280; Write bytes: 345179648; Read time: 26979; Write time: 729 02:35:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31727616; Write bytes: 310082560; Read time: 186; Write time: 1191 02:35:03 INFO - Running post-run listener: _upload_blobber_files 02:35:03 INFO - Blob upload gear active. 02:35:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:35:03 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 02:35:03 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'] 02:35:03 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 02:35:03 INFO - (blobuploader) - INFO - Open directory for files ... 02:35:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:35:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:35:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:04 INFO - (blobuploader) - INFO - Done attempting. 02:35:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:35:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:35:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:05 INFO - (blobuploader) - INFO - Done attempting. 02:35:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:35:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:35:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:08 INFO - (blobuploader) - INFO - Done attempting. 02:35:08 INFO - (blobuploader) - INFO - Iteration through files over. 02:35:08 INFO - Return code: 0 02:35:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:35:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:35:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9563dd4b2f226904c867603b9dd38ae9f66c5899b0e8addbaa17d558cd94eca25210ae517f0af474855cd74b834b58a4d55ef3617ecf1033f4874e9e28d87856", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b99a05f0ff0d4bffaedf29b19ba63afb9af8e0858c11133489537e07ba1f0d1c6fe17eb05fa5cbcdba3c590b5b75c6cafc8d3c825275f6a9c7d7aa1526d5318", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a50ea691b58edca23258245dee8c7f8515f02b715479e42f8fe615d247516f21e96b2223dce14aed314ec2f3500fce870b7ddfcf13fa07e9bd275b9a4c26295"} 02:35:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:35:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:35:08 INFO - Contents: 02:35:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9563dd4b2f226904c867603b9dd38ae9f66c5899b0e8addbaa17d558cd94eca25210ae517f0af474855cd74b834b58a4d55ef3617ecf1033f4874e9e28d87856", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b99a05f0ff0d4bffaedf29b19ba63afb9af8e0858c11133489537e07ba1f0d1c6fe17eb05fa5cbcdba3c590b5b75c6cafc8d3c825275f6a9c7d7aa1526d5318", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a50ea691b58edca23258245dee8c7f8515f02b715479e42f8fe615d247516f21e96b2223dce14aed314ec2f3500fce870b7ddfcf13fa07e9bd275b9a4c26295"} 02:35:08 INFO - Running post-run listener: copy_logs_to_upload_dir 02:35:08 INFO - Copying logs to upload dir... 02:35:08 INFO - mkdir: /builds/slave/test/build/upload/logs 02:35:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.270758 ========= master_lag: 1.56 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-28 02:35:10.059146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 02:35:10.062273) ========= 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.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/9563dd4b2f226904c867603b9dd38ae9f66c5899b0e8addbaa17d558cd94eca25210ae517f0af474855cd74b834b58a4d55ef3617ecf1033f4874e9e28d87856", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b99a05f0ff0d4bffaedf29b19ba63afb9af8e0858c11133489537e07ba1f0d1c6fe17eb05fa5cbcdba3c590b5b75c6cafc8d3c825275f6a9c7d7aa1526d5318", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a50ea691b58edca23258245dee8c7f8515f02b715479e42f8fe615d247516f21e96b2223dce14aed314ec2f3500fce870b7ddfcf13fa07e9bd275b9a4c26295"} build_url:https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010986 build_url: 'https://queue.taskcluster.net/v1/task/DLy7utl5TLaK6hamLyfrdA/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/9563dd4b2f226904c867603b9dd38ae9f66c5899b0e8addbaa17d558cd94eca25210ae517f0af474855cd74b834b58a4d55ef3617ecf1033f4874e9e28d87856", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b99a05f0ff0d4bffaedf29b19ba63afb9af8e0858c11133489537e07ba1f0d1c6fe17eb05fa5cbcdba3c590b5b75c6cafc8d3c825275f6a9c7d7aa1526d5318", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a50ea691b58edca23258245dee8c7f8515f02b715479e42f8fe615d247516f21e96b2223dce14aed314ec2f3500fce870b7ddfcf13fa07e9bd275b9a4c26295"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 02:35:10.096680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:35:10.097164) ========= 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.qt1yj28joN/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.5ag5Wo7L7E/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 02:35:10.145756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 02:35:10.244299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 02:35:10.244734) ========= ========= Total master_lag: 1.77 =========