builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0259 starttime: 1477330120.11 results: success (0) buildid: 20161024075623 builduid: 97b197ede2464edb9231fa49c27e2fdd revision: c845bfd0accb7e0c29b41713255963b08006e701 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.107144) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.107654) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.107945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.133061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.133479) ========= 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.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-24 10:28:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|: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.7M=0.001s 2016-10-24 10:28:40 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084465 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.243383) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.243814) ========= 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.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.024680 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:40.295753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-24 10:28:40.296263) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c845bfd0accb7e0c29b41713255963b08006e701 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c845bfd0accb7e0c29b41713255963b08006e701 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-24 10:28:40,378 truncating revision to first 12 chars 2016-10-24 10:28:40,378 Setting DEBUG logging. 2016-10-24 10:28:40,378 attempt 1/10 2016-10-24 10:28:40,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c845bfd0accb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 10:28:42,233 unpacking tar archive at: mozilla-central-c845bfd0accb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.094039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-24 10:28:42.412727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:42.413044) ========= script_repo_revision: c845bfd0accb7e0c29b41713255963b08006e701 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:42.413432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:42.413696) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 10:28:42.432137) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-24 10:28:42.432498) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:28:42 INFO - MultiFileLogger online at 20161024 10:28:42 in /builds/slave/test 10:28:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:28:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:28:42 INFO - {'append_to_log': False, 10:28:42 INFO - 'base_work_dir': '/builds/slave/test', 10:28:42 INFO - 'blob_upload_branch': 'Firefox', 10:28:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:28:42 INFO - 'branch': 'Firefox', 10:28:42 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:42 INFO - 'config_files': ('talos/mac_config.py',), 10:28:42 INFO - 'default_actions': ('clobber', 10:28:42 INFO - 'read-buildbot-config', 10:28:42 INFO - 'download-and-extract', 10:28:42 INFO - 'populate-webroot', 10:28:42 INFO - 'create-virtualenv', 10:28:42 INFO - 'install', 10:28:42 INFO - 'run-tests'), 10:28:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:42 INFO - 'download_minidump_stackwalk': True, 10:28:42 INFO - 'download_symbols': 'ondemand', 10:28:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:42 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:42 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:42 INFO - 'installer_path': 'installer.exe', 10:28:42 INFO - 'log_level': 'info', 10:28:42 INFO - 'log_name': 'talos', 10:28:42 INFO - 'log_to_console': True, 10:28:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:28:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:28:42 INFO - 'opt_config_files': (), 10:28:42 INFO - 'pip_index': False, 10:28:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:42 INFO - 'cmd': ('bash', 10:28:42 INFO - '-c', 10:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:42 INFO - 'enabled': True, 10:28:42 INFO - 'halt_on_failure': False, 10:28:42 INFO - 'name': 'check_screen_resolution'},), 10:28:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:42 INFO - 'cmd': ('bash', 10:28:42 INFO - '-c', 10:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:42 INFO - 'enabled': True, 10:28:42 INFO - 'halt_on_failure': False, 10:28:42 INFO - 'name': 'check_screen_resolution'},), 10:28:42 INFO - 'run_cmd_checks_enabled': True, 10:28:42 INFO - 'sps_profile': False, 10:28:42 INFO - 'sps_profile_interval': 0, 10:28:42 INFO - 'suite': 'dromaeojs-e10s', 10:28:42 INFO - 'system_bits': '32', 10:28:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:42 INFO - 'title': 't-yosemite-r7-0259', 10:28:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:42 INFO - 'use_talos_json': True, 10:28:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:28:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:42 INFO - 'work_dir': 'build'} 10:28:42 INFO - [mozharness: 2016-10-24 17:28:42.637401Z] Running clobber step. 10:28:42 INFO - Running pre-action listener: _resource_record_pre_action 10:28:42 INFO - Running main action method: clobber 10:28:42 INFO - rmtree: /builds/slave/test/build 10:28:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:28:44 INFO - Running post-action listener: _resource_record_post_action 10:28:44 INFO - [mozharness: 2016-10-24 17:28:44.663308Z] Finished clobber step (success) 10:28:44 INFO - [mozharness: 2016-10-24 17:28:44.663466Z] Running read-buildbot-config step. 10:28:44 INFO - Running pre-action listener: _resource_record_pre_action 10:28:44 INFO - Running main action method: read_buildbot_config 10:28:44 INFO - Using buildbot properties: 10:28:44 INFO - { 10:28:44 INFO - "project": "", 10:28:44 INFO - "product": "firefox", 10:28:44 INFO - "script_repo_revision": "production", 10:28:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:28:44 INFO - "repository": "", 10:28:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:28:44 INFO - "buildid": "20161024075623", 10:28:44 INFO - "pgo_build": "False", 10:28:44 INFO - "basedir": "/builds/slave/test", 10:28:44 INFO - "buildnumber": 15, 10:28:44 INFO - "slavename": "t-yosemite-r7-0259", 10:28:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:28:44 INFO - "platform": "yosemite_r7", 10:28:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:28:44 INFO - "branch": "mozilla-central", 10:28:44 INFO - "repo_path": "mozilla-central", 10:28:44 INFO - "revision": "c845bfd0accb7e0c29b41713255963b08006e701", 10:28:44 INFO - "stage_platform": "macosx64", 10:28:44 INFO - "builduid": "97b197ede2464edb9231fa49c27e2fdd", 10:28:44 INFO - "slavebuilddir": "test" 10:28:44 INFO - } 10:28:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:28:44 INFO - Running post-action listener: _resource_record_post_action 10:28:44 INFO - [mozharness: 2016-10-24 17:28:44.666484Z] Finished read-buildbot-config step (success) 10:28:44 INFO - [mozharness: 2016-10-24 17:28:44.666593Z] Running download-and-extract step. 10:28:44 INFO - Running pre-action listener: _resource_record_pre_action 10:28:44 INFO - Running main action method: download_and_extract 10:28:44 INFO - mkdir: /builds/slave/test/build/tests 10:28:44 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')]} 10:28:44 INFO - trying https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:44 INFO - Downloading https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 10:28:45 INFO - Downloaded 1199 bytes. 10:28:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:45 INFO - Using the following test package requirements: 10:28:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:28:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:28:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:28:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'jsshell-mac.zip'], 10:28:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:28:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:28:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:28:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:28:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:28:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:28:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:28:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:28:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:28:45 INFO - Fetch https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:28:46 INFO - Http code: 200 10:28:46 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 10:28:46 INFO - x-amz-cf-id: _GKtI01FMkgHIFxVqtxNujLNf71A1PyZZa6MzJlyA8V-HgUSjgGqzw== 10:28:46 INFO - x-amz-version-id: ZsKtRJZQ1aw3ASxh1Ds6twhUPVrNY_VX 10:28:46 INFO - Expected file size: 16409408 10:28:46 INFO - Obtained file size: 16409408 10:28:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:28:53 INFO - Http code: 200 10:28:53 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 10:28:53 INFO - x-amz-cf-id: YPd5GvXNPd642wTA82dZ7u8J5oGEunr7xFsrmwwNAsUJ1clEjzO92w== 10:28:53 INFO - x-amz-version-id: ZsKtRJZQ1aw3ASxh1Ds6twhUPVrNY_VX 10:28:53 INFO - Expected file size: 16409408 10:28:53 INFO - Obtained file size: 16409408 10:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:29:00 INFO - Http code: 200 10:29:00 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 10:29:00 INFO - x-amz-cf-id: p7Y7X8stmRaDyB3J6JhbolhPZnfMkexAMt_RiB8uamAoTwPiS5YZtQ== 10:29:00 INFO - x-amz-version-id: ZZVx5bpq6cWx9TCR3r0pt1jr0alDtQZU 10:29:01 INFO - Expected file size: 14409028 10:29:01 INFO - Obtained file size: 14409028 10:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:01 INFO - trying https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:29:01 INFO - Downloading https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 10:29:04 INFO - Downloaded 111235715 bytes. 10:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:29:04 INFO - mkdir: /builds/slave/test/properties 10:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:04 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:04 INFO - Contents: 10:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:29:04 INFO - Running post-action listener: _resource_record_post_action 10:29:04 INFO - Running post-action listener: set_extra_try_arguments 10:29:04 INFO - [mozharness: 2016-10-24 17:29:04.952491Z] Finished download-and-extract step (success) 10:29:04 INFO - [mozharness: 2016-10-24 17:29:04.952613Z] Running populate-webroot step. 10:29:04 INFO - Running pre-action listener: _resource_record_pre_action 10:29:04 INFO - Running main action method: populate_webroot 10:29:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:04 INFO - '--tppagecycles', 10:29:04 INFO - '7'], 10:29:04 INFO - 'tests': ['tsvgm']}}, 10:29:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['damp', 'tps']}, 10:29:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'tests': ['damp', 'tps']}, 10:29:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['dromaeo_dom']}, 10:29:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['basic_compositor_video']}, 10:29:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['a11yr', 10:29:04 INFO - 'ts_paint', 10:29:04 INFO - 'tpaint', 10:29:04 INFO - 'sessionrestore', 10:29:04 INFO - 'sessionrestore_no_auto_restore', 10:29:04 INFO - 'tabpaint']}, 10:29:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['tsvgx', 10:29:04 INFO - 'tsvgr_opacity', 10:29:04 INFO - 'tart', 10:29:04 INFO - 'tscrollx', 10:29:04 INFO - 'cart']}, 10:29:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:04 INFO - 'tsvgr_opacity', 10:29:04 INFO - 'tart', 10:29:04 INFO - 'tscrollx', 10:29:04 INFO - 'cart']}, 10:29:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'talos_options': ['--disable-e10s'], 10:29:04 INFO - 'tests': ['tp5o']}, 10:29:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'tests': ['tp5o']}, 10:29:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'talos_options': ['--disable-e10s', 10:29:04 INFO - '--xperf_path', 10:29:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:04 INFO - 'tests': ['tp5n']}, 10:29:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:04 INFO - 'talos_options': ['--xperf_path', 10:29:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:04 INFO - 'tests': ['tp5n']}}, 10:29:04 INFO - 'talos.zip': {'path': '', 10:29:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:04 INFO - Running post-action listener: _resource_record_post_action 10:29:04 INFO - [mozharness: 2016-10-24 17:29:04.971796Z] Finished populate-webroot step (success) 10:29:04 INFO - [mozharness: 2016-10-24 17:29:04.971906Z] Running create-virtualenv step. 10:29:04 INFO - Running pre-action listener: _resource_record_pre_action 10:29:04 INFO - Running main action method: create_virtualenv 10:29:04 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:04 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 10:29:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:05 INFO - Using real prefix '/tools/python27' 10:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:05 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:08 INFO - Installing pip.................done. 10:29:08 INFO - Return code: 0 10:29:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:08 INFO - Reading from file tmpfile_stdout 10:29:08 INFO - Output received: 10:29:08 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) 10:29:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29:08 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')]} 10:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:08 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')]} 10:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:08 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:08 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 10:29:08 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 10:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:08 INFO - 'HOME': '/Users/cltbld', 10:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:08 INFO - 'LOGNAME': 'cltbld', 10:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:08 INFO - 'MOZ_NO_REMOTE': '1', 10:29:08 INFO - 'NO_EM_RESTART': '1', 10:29:08 INFO - 'PAGER': '/bin/cat', 10:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:08 INFO - 'PWD': '/builds/slave/test', 10:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:08 INFO - 'SHELL': '/bin/bash', 10:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:08 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:08 INFO - 'USER': 'cltbld', 10:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:08 INFO - 'XPC_FLAGS': '0x0', 10:29:08 INFO - 'XPC_SERVICE_NAME': '0', 10:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:09 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 10:29:09 INFO - Cleaning up... 10:29:09 INFO - Return code: 0 10:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29:09 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')]} 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 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')]} 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:09 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 10:29:09 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 10:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:09 INFO - 'HOME': '/Users/cltbld', 10:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:09 INFO - 'LOGNAME': 'cltbld', 10:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:09 INFO - 'MOZ_NO_REMOTE': '1', 10:29:09 INFO - 'NO_EM_RESTART': '1', 10:29:09 INFO - 'PAGER': '/bin/cat', 10:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:09 INFO - 'PWD': '/builds/slave/test', 10:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:09 INFO - 'SHELL': '/bin/bash', 10:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:09 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:09 INFO - 'USER': 'cltbld', 10:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:09 INFO - 'XPC_FLAGS': '0x0', 10:29:09 INFO - 'XPC_SERVICE_NAME': '0', 10:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:09 INFO - Downloading/unpacking psutil>=3.1.1 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:12 INFO - Installing collected packages: psutil 10:29:12 INFO - Running setup.py install for psutil 10:29:12 INFO - building 'psutil._psutil_osx' extension 10:29:12 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 10:29:13 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 10:29:13 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 10:29:13 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 10:29:13 INFO - building 'psutil._psutil_posix' extension 10:29:13 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 10:29:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:29:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:29:13 INFO - ^ 10:29:13 INFO - 1 warning generated. 10:29:13 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 10:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:13 INFO - Successfully installed psutil 10:29:13 INFO - Cleaning up... 10:29:14 INFO - Return code: 0 10:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29:14 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')]} 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:14 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')]} 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:14 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:14 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 10:29:14 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 10:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:14 INFO - 'HOME': '/Users/cltbld', 10:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:14 INFO - 'LOGNAME': 'cltbld', 10:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:14 INFO - 'MOZ_NO_REMOTE': '1', 10:29:14 INFO - 'NO_EM_RESTART': '1', 10:29:14 INFO - 'PAGER': '/bin/cat', 10:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:14 INFO - 'PWD': '/builds/slave/test', 10:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:14 INFO - 'SHELL': '/bin/bash', 10:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:14 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:14 INFO - 'USER': 'cltbld', 10:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:14 INFO - 'XPC_FLAGS': '0x0', 10:29:14 INFO - 'XPC_SERVICE_NAME': '0', 10:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:16 INFO - Installing collected packages: mozsystemmonitor 10:29:16 INFO - Running setup.py install for mozsystemmonitor 10:29:17 INFO - Successfully installed mozsystemmonitor 10:29:17 INFO - Cleaning up... 10:29:17 INFO - Return code: 0 10:29:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29:17 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')]} 10:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:17 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')]} 10:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:17 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:17 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 10:29:17 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 10:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:17 INFO - 'HOME': '/Users/cltbld', 10:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:17 INFO - 'LOGNAME': 'cltbld', 10:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:17 INFO - 'MOZ_NO_REMOTE': '1', 10:29:17 INFO - 'NO_EM_RESTART': '1', 10:29:17 INFO - 'PAGER': '/bin/cat', 10:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:17 INFO - 'PWD': '/builds/slave/test', 10:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:17 INFO - 'SHELL': '/bin/bash', 10:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:17 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:17 INFO - 'USER': 'cltbld', 10:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:17 INFO - 'XPC_FLAGS': '0x0', 10:29:17 INFO - 'XPC_SERVICE_NAME': '0', 10:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:17 INFO - Downloading/unpacking jsonschema==2.5.1 10:29:17 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 10:29:17 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 10:29:17 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 10:29:17 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 10:29:17 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 10:29:17 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 10:29:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29:19 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 10:29:19 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 10:29:19 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 10:29:19 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 10:29:19 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 10:29:19 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 10:29:20 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:20 INFO - warning: no files found matching '*.txt' 10:29:20 INFO - no previously-included directories found matching 'build' 10:29:20 INFO - no previously-included directories found matching 'dist' 10:29:20 INFO - no previously-included directories found matching '.git*' 10:29:20 INFO - Installing collected packages: jsonschema, functools32 10:29:20 INFO - Running setup.py install for functools32 10:29:20 INFO - warning: no files found matching '*.txt' 10:29:20 INFO - no previously-included directories found matching 'build' 10:29:20 INFO - no previously-included directories found matching 'dist' 10:29:20 INFO - no previously-included directories found matching '.git*' 10:29:20 INFO - Successfully installed jsonschema functools32 10:29:20 INFO - Cleaning up... 10:29:20 INFO - Return code: 0 10:29:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:20 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:20 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 10:29:20 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 10:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:20 INFO - 'HOME': '/Users/cltbld', 10:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:20 INFO - 'LOGNAME': 'cltbld', 10:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:20 INFO - 'MOZ_NO_REMOTE': '1', 10:29:20 INFO - 'NO_EM_RESTART': '1', 10:29:20 INFO - 'PAGER': '/bin/cat', 10:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:20 INFO - 'PWD': '/builds/slave/test', 10:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:20 INFO - 'SHELL': '/bin/bash', 10:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:20 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:20 INFO - 'USER': 'cltbld', 10:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:20 INFO - 'XPC_FLAGS': '0x0', 10:29:20 INFO - 'XPC_SERVICE_NAME': '0', 10:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:21 INFO - Cleaning up... 10:29:21 INFO - Return code: 0 10:29:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:21 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:21 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 10:29:21 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 10:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:21 INFO - 'HOME': '/Users/cltbld', 10:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:21 INFO - 'LOGNAME': 'cltbld', 10:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:21 INFO - 'MOZ_NO_REMOTE': '1', 10:29:21 INFO - 'NO_EM_RESTART': '1', 10:29:21 INFO - 'PAGER': '/bin/cat', 10:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:21 INFO - 'PWD': '/builds/slave/test', 10:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:21 INFO - 'SHELL': '/bin/bash', 10:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:21 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:21 INFO - 'USER': 'cltbld', 10:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:21 INFO - 'XPC_FLAGS': '0x0', 10:29:21 INFO - 'XPC_SERVICE_NAME': '0', 10:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:21 INFO - Downloading/unpacking blobuploader==1.2.4 10:29:21 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 10:29:21 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 10:29:21 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 10:29:21 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 10:29:21 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 10:29:21 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 10:29:23 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:25 INFO - Downloading docopt-0.6.1.tar.gz 10:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:25 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:25 INFO - Running setup.py install for blobuploader 10:29:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:25 INFO - Running setup.py install for requests 10:29:25 INFO - Running setup.py install for docopt 10:29:25 INFO - Successfully installed blobuploader requests docopt 10:29:25 INFO - Cleaning up... 10:29:25 INFO - Return code: 0 10:29:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:25 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')]} 10:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:25 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')]} 10:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:25 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:25 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 10:29:25 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 10:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:25 INFO - 'HOME': '/Users/cltbld', 10:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:25 INFO - 'LOGNAME': 'cltbld', 10:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:25 INFO - 'MOZ_NO_REMOTE': '1', 10:29:25 INFO - 'NO_EM_RESTART': '1', 10:29:25 INFO - 'PAGER': '/bin/cat', 10:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:25 INFO - 'PWD': '/builds/slave/test', 10:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:25 INFO - 'SHELL': '/bin/bash', 10:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:25 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:25 INFO - 'USER': 'cltbld', 10:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:25 INFO - 'XPC_FLAGS': '0x0', 10:29:25 INFO - 'XPC_SERVICE_NAME': '0', 10:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-nZjRGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-96cHwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-uho44W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ja29pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Vde2lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-zQnOQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-a526d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-X6UGtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-t908GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-LcNNxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-bIytkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-h3q2UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-DaPwmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-6Ky31U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-s9ip6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-1JD9a2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:27 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-JEIn39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:29:28 INFO - Running setup.py install for manifestparser 10:29:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:29:28 INFO - Running setup.py install for mozcrash 10:29:28 INFO - Running setup.py install for mozdebug 10:29:28 INFO - Running setup.py install for mozdevice 10:29:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:29:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:29:28 INFO - Running setup.py install for mozfile 10:29:28 INFO - Running setup.py install for mozhttpd 10:29:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:29:28 INFO - Running setup.py install for mozinfo 10:29:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Running setup.py install for mozInstall 10:29:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Running setup.py install for mozleak 10:29:29 INFO - Running setup.py install for mozlog 10:29:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Running setup.py install for moznetwork 10:29:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Running setup.py install for mozprocess 10:29:29 INFO - Running setup.py install for mozprofile 10:29:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:29:29 INFO - Running setup.py install for mozrunner 10:29:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:29:30 INFO - Running setup.py install for mozscreenshot 10:29:30 INFO - Running setup.py install for moztest 10:29:30 INFO - Running setup.py install for mozversion 10:29:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:29:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:29:30 INFO - Cleaning up... 10:29:30 INFO - Return code: 0 10:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:30 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:30 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 10:29:30 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 10:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:30 INFO - 'HOME': '/Users/cltbld', 10:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:30 INFO - 'LOGNAME': 'cltbld', 10:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:30 INFO - 'MOZ_NO_REMOTE': '1', 10:29:30 INFO - 'NO_EM_RESTART': '1', 10:29:30 INFO - 'PAGER': '/bin/cat', 10:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:30 INFO - 'PWD': '/builds/slave/test', 10:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:30 INFO - 'SHELL': '/bin/bash', 10:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:30 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:30 INFO - 'USER': 'cltbld', 10:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:30 INFO - 'XPC_FLAGS': '0x0', 10:29:30 INFO - 'XPC_SERVICE_NAME': '0', 10:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:30 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-naYrKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:30 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)) 10:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:30 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-FbyH1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-phTWsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-B7ynpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-H7yCfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-xOhR80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-8ByjwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-RVrOGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Ymjz4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-snpXj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:31 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)) 10:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:31 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-eURNO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:32 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)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ecEHj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:32 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)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-pbc4fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:32 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)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-1cyT8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-NWfa2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:32 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)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-RdkICu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:32 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)) 10:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:32 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-J96WQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:32 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)) 10:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:32 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)) 10:29:32 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)) 10:29:32 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)) 10:29:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29:32 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 10:29:32 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 10:29:32 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 10:29:32 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 10:29:32 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 10:29:32 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 10:29:35 INFO - Downloading blessings-1.6.tar.gz 10:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:29:35 INFO - Installing collected packages: blessings 10:29:35 INFO - Running setup.py install for blessings 10:29:35 INFO - Successfully installed blessings 10:29:35 INFO - Cleaning up... 10:29:35 INFO - Return code: 0 10:29:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:29:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:35 INFO - Reading from file tmpfile_stdout 10:29:35 INFO - Current package versions: 10:29:35 INFO - blessings == 1.6 10:29:35 INFO - blobuploader == 1.2.4 10:29:35 INFO - docopt == 0.6.1 10:29:35 INFO - functools32 == 3.2.3-2 10:29:35 INFO - jsonschema == 2.5.1 10:29:35 INFO - manifestparser == 1.1 10:29:35 INFO - mozInstall == 1.12 10:29:35 INFO - mozcrash == 0.17 10:29:35 INFO - mozdebug == 0.1 10:29:35 INFO - mozdevice == 0.48 10:29:35 INFO - mozfile == 1.2 10:29:35 INFO - mozhttpd == 0.7 10:29:35 INFO - mozinfo == 0.9 10:29:35 INFO - mozleak == 0.1 10:29:35 INFO - mozlog == 3.4 10:29:35 INFO - moznetwork == 0.27 10:29:35 INFO - mozprocess == 0.23 10:29:35 INFO - mozprofile == 0.28 10:29:35 INFO - mozrunner == 6.12 10:29:35 INFO - mozscreenshot == 0.1 10:29:35 INFO - mozsystemmonitor == 0.3 10:29:35 INFO - moztest == 0.7 10:29:35 INFO - mozversion == 1.4 10:29:35 INFO - psutil == 3.1.1 10:29:35 INFO - requests == 1.2.3 10:29:35 INFO - wsgiref == 0.1.2 10:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:35 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')]} 10:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:37 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:37 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 10:29:37 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 10:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:37 INFO - 'HOME': '/Users/cltbld', 10:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:37 INFO - 'LOGNAME': 'cltbld', 10:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:37 INFO - 'MOZ_NO_REMOTE': '1', 10:29:37 INFO - 'NO_EM_RESTART': '1', 10:29:37 INFO - 'PAGER': '/bin/cat', 10:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:37 INFO - 'PWD': '/builds/slave/test', 10:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:37 INFO - 'SHELL': '/bin/bash', 10:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:37 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:37 INFO - 'USER': 'cltbld', 10:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:37 INFO - 'XPC_FLAGS': '0x0', 10:29:37 INFO - 'XPC_SERVICE_NAME': '0', 10:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 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)) 10:29:37 INFO - Cleaning up... 10:29:37 INFO - Return code: 0 10:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:29:37 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')]} 10:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:37 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')]} 10:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:37 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 0x107e18490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8d857a860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, '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.1FovEPTmFs/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.VUFotbaHOY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 10:29:37 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 10:29:37 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 10:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:37 INFO - 'HOME': '/Users/cltbld', 10:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:37 INFO - 'LOGNAME': 'cltbld', 10:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:37 INFO - 'MOZ_NO_REMOTE': '1', 10:29:37 INFO - 'NO_EM_RESTART': '1', 10:29:37 INFO - 'PAGER': '/bin/cat', 10:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:37 INFO - 'PWD': '/builds/slave/test', 10:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:37 INFO - 'SHELL': '/bin/bash', 10:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:29:37 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:37 INFO - 'USER': 'cltbld', 10:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:37 INFO - 'XPC_FLAGS': '0x0', 10:29:37 INFO - 'XPC_SERVICE_NAME': '0', 10:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:29:37 INFO - Cleaning up... 10:29:37 INFO - Return code: 0 10:29:37 INFO - Running post-action listener: _resource_record_post_action 10:29:37 INFO - Running post-action listener: _start_resource_monitoring 10:29:37 INFO - Starting resource monitoring. 10:29:37 INFO - [mozharness: 2016-10-24 17:29:37.910089Z] Finished create-virtualenv step (success) 10:29:37 INFO - [mozharness: 2016-10-24 17:29:37.911175Z] Running install step. 10:29:37 INFO - Running pre-action listener: _resource_record_pre_action 10:29:37 INFO - Running main action method: install 10:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:38 INFO - Reading from file tmpfile_stdout 10:29:38 INFO - Detecting whether we're running mozinstall >=1.0... 10:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:29:38 INFO - Reading from file tmpfile_stdout 10:29:38 INFO - Output received: 10:29:38 INFO - Usage: mozinstall [options] installer 10:29:38 INFO - Options: 10:29:38 INFO - -h, --help show this help message and exit 10:29:38 INFO - -d DEST, --destination=DEST 10:29:38 INFO - Directory to install application into. [default: 10:29:38 INFO - "/builds/slave/test"] 10:29:38 INFO - --app=APP Application being installed. [default: firefox] 10:29:38 INFO - mkdir: /builds/slave/test/build/application 10:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:30:12 INFO - Reading from file tmpfile_stdout 10:30:12 INFO - Output received: 10:30:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:30:12 INFO - Running post-action listener: _resource_record_post_action 10:30:12 INFO - [mozharness: 2016-10-24 17:30:12.797680Z] Finished install step (success) 10:30:12 INFO - [mozharness: 2016-10-24 17:30:12.797859Z] Running run-tests step. 10:30:12 INFO - Running pre-action listener: _resource_record_pre_action 10:30:12 INFO - Running main action method: run_tests 10:30:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:12 INFO - 2016-10-24 10:30:12.812 screenresolution[1645:7818] starting screenresolution argv=screenresolution get 10:30:12 INFO - 2016-10-24 10:30:12.815 screenresolution[1645:7818] Display 0: 1600x1200x32@60 10:30:12 INFO - 2016-10-24 10:30:12.820 screenresolution[1646:7820] starting screenresolution argv=screenresolution list 10:30:12 INFO - Available Modes on Display 0 10:30:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:13 INFO - Intel Iris: 10:30:13 INFO - Chipset Model: Intel Iris 10:30:13 INFO - Type: GPU 10:30:13 INFO - Bus: Built-In 10:30:13 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:13 INFO - Vendor: Intel (0x8086) 10:30:13 INFO - Device ID: 0x0a2e 10:30:13 INFO - Revision ID: 0x0009 10:30:13 INFO - Displays: 10:30:13 INFO - Display: 10:30:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:13 INFO - Display Serial Number: 1600x1200 60 10:30:13 INFO - Main Display: Yes 10:30:13 INFO - Mirror: Off 10:30:13 INFO - Online: Yes 10:30:13 INFO - Rotation: Supported 10:30:13 INFO - Return code: 0 10:30:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:30:13 INFO - Python 2.7.3 10:30:13 INFO - Return code: 0 10:30:13 INFO - grabbing minidump binary from tooltool 10:30:13 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')]} 10:30:13 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 0x107c2cd30>, '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 0x107a52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c24b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:30:13 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 10:30:13 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 10:30:13 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 10:30:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:30:13 INFO - Return code: 0 10:30:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:30:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:30:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:30:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:30:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:30:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--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 10:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0259 --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 10:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1FovEPTmFs/Render', 10:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:13 INFO - 'HOME': '/Users/cltbld', 10:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:13 INFO - 'LOGNAME': 'cltbld', 10:30:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:30:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:13 INFO - 'MOZ_NO_REMOTE': '1', 10:30:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:30:13 INFO - 'NO_EM_RESTART': '1', 10:30:13 INFO - 'PAGER': '/bin/cat', 10:30:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:13 INFO - 'PWD': '/builds/slave/test', 10:30:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:13 INFO - 'SHELL': '/bin/bash', 10:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUFotbaHOY/Listeners', 10:30:13 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 10:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:13 INFO - 'USER': 'cltbld', 10:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:13 INFO - 'XPC_FLAGS': '0x0', 10:30:13 INFO - 'XPC_SERVICE_NAME': '0', 10:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--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 10:30:13 INFO - mozversion application_buildid: 20161024075623 10:30:13 INFO - mozversion application_changeset: c845bfd0accb7e0c29b41713255963b08006e701 10:30:13 INFO - mozversion application_display_name: Nightly 10:30:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:30:13 INFO - mozversion application_name: Firefox 10:30:13 INFO - mozversion application_remotingname: firefox 10:30:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:30:13 INFO - mozversion application_vendor: Mozilla 10:30:13 INFO - mozversion application_version: 52.0a1 10:30:13 INFO - mozversion platform_buildid: 20161024075623 10:30:13 INFO - mozversion platform_changeset: c845bfd0accb7e0c29b41713255963b08006e701 10:30:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:30:13 INFO - mozversion platform_version: 52.0a1 10:30:13 INFO - using testdate: 1477330213 10:30:13 INFO - actual date: 1477330213 10:30:13 INFO - starting webserver on 'localhost:49270' 10:30:13 INFO - SUITE-START | Running 2 tests 10:30:13 INFO - TEST-START | dromaeo_css 10:30:13 INFO - operating with platform_type : mac_ 10:30:13 INFO - Initialising browser for dromaeo_css test... 10:30:13 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpSJ9iXw/profile http://localhost:49270/getInfo.html) 10:30:16 INFO - PROCESS | 1656 | 2016-10-24 10:30:16.442 plugin-container[1658:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 10:30:16 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:16 INFO - PROCESS | 1656 | 2016-10-24 10:30:16.445 plugin-container[1658:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e47, name = 'com.apple.CFPasteboardClient' 10:30:16 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:16 INFO - PROCESS | 1656 | 2016-10-24 10:30:16.445 plugin-container[1658:7971] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:16 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 10:30:16 INFO - PROCESS | 1656 | colorDepth:24 10:30:16 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 10:30:16 INFO - PROCESS | 1656 | __metrics 10:30:16 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:30:17 INFO - TEST-INFO | 1656: exit 0 10:30:17 INFO - Browser initialized. 10:30:17 INFO - Running cycle 1/1 for dromaeo_css test... 10:30:17 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpSJ9iXw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:19 INFO - PROCESS | 1659 | 2016-10-24 10:30:19.896 plugin-container[1660:8093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 10:30:19 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:19 INFO - PROCESS | 1659 | 2016-10-24 10:30:19.899 plugin-container[1660:8093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 10:30:19 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:19 INFO - PROCESS | 1659 | 2016-10-24 10:30:19.899 plugin-container[1660:8093] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:29 INFO - PROCESS | 1659 | RSS: Main: 236732416 10:30:29 INFO - PROCESS | 1659 | 10:31:19 INFO - PROCESS | 1659 | 1477330279112 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] 10:33:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:33:34 INFO - PROCESS | 1659 | RSS: Main: 251006976 10:33:34 INFO - PROCESS | 1659 | 10:36:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:36:37 INFO - PROCESS | 1659 | RSS: Main: 263516160 10:36:37 INFO - PROCESS | 1659 | 10:39:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:39:39 INFO - PROCESS | 1659 | RSS: Main: 268111872 10:39:39 INFO - PROCESS | 1659 | 10:42:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 10:42:42 INFO - PROCESS | 1659 | RSS: Main: 267726848 10:42:42 INFO - PROCESS | 1659 | 10:45:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 10:45:45 INFO - PROCESS | 1659 | RSS: Main: 273055744 10:45:46 INFO - PROCESS | 1659 | 10:48:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 10:48:49 INFO - PROCESS | 1659 | RSS: Main: 275701760 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | __start_tp_report 10:48:49 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 10:48:49 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 10:48:49 INFO - PROCESS | 1659 | |i|pagename|runs| 10:48:49 INFO - PROCESS | 1659 | |0;dojo.html;3122.29748306631;3281.1469018055313;3339.716124129449;3371.0729549373905;3374.611919629242;4533.727976321423;4701.15379231739;4950.084234416636;5472.50747432731;5491.725413729312;4354.325079612657;4454.420925279709;4572.359869618248;5480.903819236149;5567.331920169575;5031.572316353115;5074.847754567369;5100.795968161287;5126.897462050757;5129.897402051975;5100.20946753252;5129.897402051956;5168.612354073441;5179.197224430201;5190.844274671747;5168.224766285069;5175.534201921826;5176.12005958986;5181.870453238662;5190.377154741436;4489.685721999462;4929.753512324405;5144.948550514503;5170.612204084689;5175.560077393426;4604.171249175147;5111.284420181178;5123.410807757118;5130.820421285237;5147.356580427447;5195.168772996302;5506.669599823983;5563.721813909328;5670.432956704338;5709.77160913563;4998.800047998076;6070.908936365958;6072;6094.390560943914;6115.663638499881;6046.062860256668;6079.148919151322;6109.908351374733;6119.388061193889;6133.969330153365;5703.743331550132;5705.429457054262;5734.340550836624;5758.452946970031;5784.652920824764;6131.0803379492545;6147.72335244915;6162.599431036968;6177.752889884444;6186.1648677428875;5752.36723960364;5754.165645981309;5820.912686309709;5998.130271110665;6011.188489553854;5019.196928491424;5185;5687.971560142173;6019.518438524908;6032.487238584682;6031.547633927473;6031.9396806032255;6042.879142417215;6051.667158306335;6066.878662426727;6045.909311360333;6053.545984051214;6061.060535616986;6097.603655762361;6121.0206366981065;4778.307145463958;4810.831620893322;4814.422269327703;4823.059503396804;4829.299751536078;4282.550332215135;4328.761918094535;4335;4352.9129417412105;4391.231534481517;4111.671066314688;5547.001539722808;5604.943950560524;5627.915581266284;5682.573806964494;6059.6364218147055;6178.969105154445;6200.193974783249;6206.348333424925;6211.068339748984;5489.34127904646;5638.323401191883;5638.492535671728;5645.294338207724;5677.09166533361;5230.686158830483;5252.1596544552685;5267.367915850123;5272.947270527246;5363.03465376236;6213.720382582795;6222.471089957405;6222.875542489034;6242.687865606792;6245.875082498415;6150.538709596887;6213.285472170758;6214.844628884314;6221.346758590283;6244.687765611792;5022.824201152942;6119.350229749377;6165.660888651168;6188.288346840171;6248.312685604489;6018.428249316308;6065.332813390435;6066.4540191382985;6082.787102451393;6098.420650038239;5190.766415511386;6111.877762444815;6168.784092556739;6212.565120441706;6220.222472190976;4205.495340559152;4468.949796797685;4584.426946631671;4940.925886111783;5254.6847189168775;5975;5987.191729116514;5990.251218597676;6003.939960600338;6039.849003774765;5158.942416804621;5679.091345384719;5759;5803.47768700819;5862.648241105548;4659;5828.009238429562;5912.704364781658;5989.071693887455;5996.700164991646;5697.57268204878;5753.280839895013;5757.625754325955;5798.478136967693;5803.709814509342 10:48:49 INFO - PROCESS | 1659 | |1;ext.html;461.181403009658;497.7237633113623;500.09982032341776;521.6504941689069;530.6975024236184;31772.523412148836;39160.41259381108;40879.79560102199;43642.34536481955;43668.908277293005;42333.94165145873;42785.786071069604;42834.57165428345;42836.78581607088;42841;8098.433109682324;9692.176165025981;9730.464824434654;9763.853542196837;9776.364536305118;9523.95238023809;9527.571259293349;9576.856347154799;9607.27945404094;9611.519424028771;9401.670941517048;9610.519474026305;9633.795775528059;9638.03619638034;9661.90338096617;8546.444481108709;9630.662926797599;9634.47010414427;9650.227981761442;9678.516074196263;9531.332806703464;9533.189678877303;9539.379940303857;9606.711798646053;9658.323917325719;32329.838350808095;39995.800021;40194.79902600498;40340;40366.39450408246;3325.451300535409;3403.081168084603;3430.6569343065744;3464.20323325634;3550.7336949728874;30167.09498715042;30466.695333046606;30953;31256.687433125833;31755.84122079398;3193.3613277344625;3241.319322942185;3250.4799232122746;3272;3333.4666453367345;493122;494249;496643;496807;497365;51887.74056129771;58840.823183536944;61766.69116654478;61906.69046654738;62219.68890155611;49756.75121624369;56204.313870583945;64308.67845660742;65134.67432662806;65662.67168664222;13323.66690832737;15742.527724168292;15967.44113956006;16082.43711470093;16149.677006459806;12352.320622365856;12446.564370246997;12451.315177665134;12460.940820030217;12475;17358.44126758987;18855.622887542446;18889.149988250563;18985.33551325697;19120.521986950436;3160.4627213373783;6805.319468053254;7822.413319000984;8031.152834848018;8086.353091752646;819.7458787775809;879.564615515324;889.8709687095335;978.3787295067683;1018.4907546226887;26897.731022689524;27615.723842761316;30024.54963175554;30437.39125217483;31759.25324107196;922.6493932305681;991.7867658453467;1023.5752162852333;1078.0997866781324;1179.8525184351956;14324.928375358057;15313.004654697408;15862.365505379656;15927.920360398124;15993.000874890638;618392.908035466;618862;622884;626215.8689206524;627306.8634656797;58374.4162558369;58495.83008339809;58800.7059964706;58857.70571147202;58873.70563147157;1350.7636163671318;1367.8290213723285;1374.6288502104576;1388.9305534723223;1395.762720337545;27579;29770.85114574413;29806;30245.09264722062;30518.694813052472;50466.99066018732;50629.49370506248;50745.74627126988;50750;50800.23799642934;582176;587229;588410;591639;592000.0399997972;25899.482010359312;26077.087301944342;26175.083872064388;26340.736592634596;26371.340716482242;45984.770076149405;46601;47651.808704782656;48104.75947620379;48219;31530.527042094847;38480.615193847705;39086.82739517819;39649.00877477971;40671.38992915049;38815.80592097021;39017.60982390253;39070.4139437903;39710.404343934286;40157.397639034854;38011;38358.42462363011;38782.4182637255;38792;38796.80601596974;28703.138905832857;31602.841985789924;34046.65953340435;34111.48832767461;34699.13252168716;24790.628140578265;24894.502109958063;25239.747602524476;25258.494830103664;25539.744602553736 10:48:49 INFO - PROCESS | 1659 | |2;jquery.html;331.3786650031191;353.1436267052398;360.5061066339115;378.6610983170061;398.3049578485543;14947.775783363259;20058;20294;20719.378418647426;20855.791442085574;19990.200391984305;20153.29463468779;20466.897665511653;20603.175872965065;20643.896780516116;4260.147970405916;5066.594672426216;5636.379998200195;5899.203607512974;5911.586188966774;4391.86824395267;4954.281629163769;5940.2871655401195;5959.582829201958;5963.821085367446;4851.660383773137;4915.361003069596;5572.3034620672415;5819.243498345209;5903.586748927577;4801.2798080287885;5755.510781583571;5804.564657650693;5831.912521312183;5833.941660583382;4239.809208585591;4906;4946.2580612908;5057.974710126463;5087.79648814047;23919.52160956771;24758.876205619035;24833.379165520826;24948.750512494826;25021;2178.433607262123;2372.608519594265;2407.855528668268;2422.4125129931163;2428.441458464563;13832.654183645389;14697.191654459;15326.693466130615;15433.151176685278;15506.922465387714;1804.3865085870736;1849.3804575467234;1996.800319968006;2005.839532837384;2011.8591698581106;124440;126950;127495;128001.71998279991;128851;11178.60874869392;11297.887021129683;11500.942495287638;11507.45455545055;11653.47559359814;14424;14431.639209019859;14465.349059292357;14476;14486.565403037926;8106.310963568045;8160.97987751531;8249.422540422232;8275.793105172419;8391.370647201484;8016.55908925003;8021.5187088774865;8137.959310203411;8151.510909345339;8188.918110818935;3671.7980511072146;3789.5073640426567;4491.663125265618;4493.326001099796;4589.036302376471;1904.4762690260013;1916.875403098794;1929.1608150454597;1935.4387227703808;1976.2589029114083;657.4773055420952;731.1263040666458;744.3624027140313;816.3673153306236;845.3913182508584;17149;19091.568132390126;19844.404667859988;20778.688319675217;20968.266110686054;888.4806980064034;912.7124955638952;928.2898582584194;943.3160958305174;950.1780959470009;10667.253292269621;11357.886421135683;11367.488463019185;11462.083033357312;11468.770624587629;589622;593822;593957;610877;611739;105613;113351.86648133578;130203;132445;133272;959.9808003839884;975.5707488704948;1024.3853687787416;1031.9226058045526;1033.0702367868978;19278.71081933744;19288.614227715647;19695;19697;19734.210631574577;54786.45213547813;55153.72423137858;55827;56206.875862481706;56601.71699141478;75562.48875022579;80539;81340.59329703313;81435;81456.59271703604;7734.497257678233;7832.647530861011;7994.080680721791;8133.146019668086;8249.515914166925;8873.467591944504;9011.594478248626;12109.576164834189;12343.753124937271;12513.374331283581;8506;9649.324547281623;10370.896291037296;10514.789704206027;10517.211209159497;10351.741206469898;10577.048065674126;10593.947030265108;10804.351738895377;11115.666530004113;10634.521446534769;10764;10810.513526891466;11036;11239.438028098726;7846.353428865567;7964.84070318602;8009.815265530946;8205.179482051746;8249.422540422112;10794.998679404274;10966.561337546409;11037.724056898642;11079.61221357281;11080.390578517936 10:48:49 INFO - PROCESS | 1659 | |3;mootools.html;1422.5945605502432;1554.5880341709449;1786.4908501077196;1864.2915692037025;1924.1533725160918;8028.598570071487;8168.509889406632;8276.17238276172;8749.343799215061;8765.693200737975;8255.628496717647;8567.100454452271;8567.700130495434;8663.653453861838;8683.392162548624;2280.1791355112146;2290.0839664134314;2318.9072437102495;2320.8607483550954;2323.7908588227056;2280.9543809123807;2316.5598536278053;2321.7097862767155;2328.4528135888054;2345.1791872844537;2288.553732022256;2294.816414686821;2304.1704986204954;2313.421644588853;2317.4206448387904;2252.8986195621237;2256.638937769957;2265.0939624150305;2265.9773402265932;2279.6352583586713;2286.8513546619415;2293.793558579736;2293.8050265727434;2301.7813307735737;2302.6085565453914;8569.871451928226;8709.69516066941;8739.737807865775;8739.956300218522;8752.124787521198;4651.232546629802;4952.678075925053;5084.186530155194;5163.457836927106;5261.394939581959;3380.323935212967;3464.2626548019566;3507.368673638744;3575.8927232183078;3576.3920133577353;1014.2747935226348;1034.9793004139874;1043.8121138195122;1052.884182739898;1062.0388548363744;8583.828323433496;9893.406395616214;9932.90067099327;11089.667309980632;11100.5559777608;4421.53573874745;4525.208088584484;4636.350910872413;4641.883952901137;4643.11780761661;9496.762580935394;9539.99830017852;9608.807823843485;9615;9615.375000624937;3907.7069219808627;5201.557867581222;5213.035588416155;5221.034108689904;5229.189475631283;4417.801198945991;4560.726356418625;4632.930506042412;4768.046390721869;4800.543948324904;3803.486529318562;3811.5616704079384;3917.8432862685745;3923.5291764988383;3938.606139386096;1267.9366031698562;1269.695273134441;1281.1039661301327;1289.6711338608595;1291.06985057338;711.7615598774469;721.8991038665971;808.7169490678216;853.470848074198;870.1820288928387;5551.500364967173;5667.489925906688;5804.6807425592;5842.415758424124;5845.649261044351;428.036916936892;430.9224339618836;437.42478640587524;439.15462734237104;444.35346570739495;3848.3650197717625;3892.552356479041;3976.383660532622;3992.4210989406374;4025;9853.408795472295;10084.848727269096;10123.493825308706;10215.642452514126;10215.978402159724;9747.707568772948;10025.24810639205;10193.082622564005;10199.184065274909;10202.387856728472;7725.575093369806;7860.646270917822;7862.685492580347;7865.882011769828;7906.090799558125;5560.91658625129;5571.192177134374;5636.408177141505;5662.66024038559;5684.886302274014;9252;9417.62329506812;9482.19401350893;9489.908660503854;9524.285678573997;9369.156775890202;9560.61757529691;9608.231341492525;9823.705288841345;9840.852387214054;6447.161868957006;7132.037174981519;7192.820179495555;7364.263573642807;7556.168821429528;6740.595564266159;8244.381671374551;8256.711015114442;8266.338692904674;8268.421210515206;7208.990741296117;7340.816479588053;7359.300866417681;7436.256374362521;7447.441441891771;7059.788206353817;7170.354668079898;7200.927990720235;7262.745803896838;7284.890726639211;6762.763303284361;6836.983089988565;6873.656317184221;6882.655867206719;6911.481638877205;4596.264597664358;4883.536064073907;4922.827701030446;4936.777844996912;5011.223260394645;4146.295129827876;4836.395450568679;4960.677555958851;5021.5229553192385;5030.723310217973 10:48:49 INFO - PROCESS | 1659 | |4;prototype.html;266.6426987836299;303.7387846452051;304.18781852453947;311.3586012813604;317.2291332063086;4153.961509622594;4914.852554423369;5202.219667049944;5344.732763361831;5447.45525447455;5259.6055295852775;5460.044492213867;5512.476314750093;5515.779368825242;5562.554995600352;3475.5308033415417;4183.284853210192;4212.35718069953;4212.578742125794;4230.619244268015;3660.469231961364;4008.799560022002;4059.1069964607887;4251.2135254977775;4263.827447451945;3450.3271861986923;4003.819828107742;4093.447384603085;4178.850816025602;4182.665386769051;3451.8964431066984;4166.166766646683;4171.874843754662;4191.140816132685;4222.85220017301;3646.361886669849;3649.4525821127045;3652.196516766307;3653.178034942117;3657.926841463156;4393.978030109861;4523.36672865799;4905.386826646669;5238.764255608507;5374.785008599691;4419.4808958893445;4683.437987441529;4745.928811067837;4757.976210118927;4769.284607308897;5034;5214.478552144793;5433.429489903582;5796.043652797325;5875.500582450497;3797.2785170817456;3906.2187562487334;3913.882583522499;3937.862174823896;3943.5859234780223;5540.002799496089;5767.971160144214;6146.7233974471255;6163.459135216196;6234.625922444668;3358.429067058606;3981.124152686404;3999.2601368746873;4010.5187377514885;4026.5772093929713;3245.724113450336;3945.822437990297;4007.779572123503;4064.7967601619803;4082.510098788164;4137.710360274812;4702.318163866289;4705.717656940595;4738.502457242009;4843.418789745253;5026.874328141826;5683.2895888014;5872.207252021008;5978.372270911578;5983.311919129356;1258.672745086284;1289.2457912121326;1333.8799508044322;1353.5668586052368;1373.4643489039283;800.9399295052777;835.7701632051113;902.5757893789909;927.6753136402206;927.9189743948225;5053.84838454847;5093.898122037538;5333.306670132858;5417.349918009864;5428.4028756836715;5213.748700311898;5601.439856014447;5910.172575839386;5943.68196403403;5964.31410387811;4480.551944805558;5368.0943265141095;5402.459754024566;5405.999890020359;5492.093804522289;2569.486102779452;2885.7447010550477;2982.9410559251587;3185.5380969759253;3216.7748257621743;4898.804047838046;5150.510701483353;6393.6163830170335;6447.451966582799;6488.513361497812;4938.950610493921;5984.640921544721;6199.16311297969;6228.781992630236;6277.560570760094;5265.552428043659;6299.5275354348605;6335.3981371769605;6338;6349.7142628580905;5153.6907785532985;5220.877511335081;5767.769289228384;5919.348871624032;6030.517558595214;5133.8716532087;5647.378788333198;6005.579609427473;6016.669083200466;6022.8795424090395;6157.599756015845;6381.712822923072;6396.32838551964;6403.935960640334;6430.3891130342545;3527.5237842891906;3703.9814800925824;3877.7673339598587;3940.2907476654477;3980.0099950024987;4683.437987441529;4993.4756850530175;5458.307924543405;6035.245594300713;6145.723442445012;5350.48527192219;5705.971470142623;5794.63826000881;5832.804275123504;5836;5909.91135132982;5942.554308426799;5946.613470124428;5955.434233747787;5963.522918166623;4822;5197.350331208599;5445.40100588927;5690.914636280542;5868.794592189253;5483.122700367922;5643.407442218508;5836.227791799031;5841.532677385714;5871.6770577618645;5139.537441630271;5746.7701291949525;5761.193432919311;5809.535237180931;5819.5053420459735 10:48:49 INFO - PROCESS | 1659 | |5;yui.html;719.6905330707796;727.3599232675246;732.1945859554487;770.0259172147234;823.267292110022;14938.626534336647;15979.121148336837;15995.200239988013;16007.119608421532;16016.359345626175;10740.677779666603;11650.82523762142;12011.639650810468;13006.47974081038;14365.138091714478;874.8687696845492;877.4252864373846;902.747230775384;906.1708536688922;909.5315912305168;519.3508114856411;718.7879575525207;904.4708845325459;906.9229115525188;907.8456662367413;909.2680392284209;930.5812384427;936.4194199596226;951.538503825644;962.523550842334;948.1892981500821;949.378157306965;951.600327862296;952.2286947572488;953.3183773601838;693.2928413018693;705.1362081450234;707.2114592229627;707.8938159276098;708.5819366573746;14536.709265814625;14773.187474688888;14815.259237038214;14826.481073162495;14855;13693.520726774515;13700.794488082687;13716.794248086288;13872.791908121386;14067.226302553356;12559.612110592245;12660.303683297416;12662.36688165588;12707.237565746085;13145.539906103337;4409.9118017639785;4419.9337009944875;4423.12366986312;4467.7319360838455;4640.67515273931;111950;112775;118356.8164318363;120815.3959230207;121370;10942.671719848417;10959;10979.945100274448;10985.340879547253;11077.11383089351;17562.64874702499;21110.47223819417;21400.571988560452;21427.357179284645;21458.678119828215;7620.825225368294;7638.083429988437;7772.222777722295;7857.646405911748;7910.090339611018;4134.91730165395;4233.872983810491;4275.5724427557;4275.636570891509;4346.826126954887;2637.551616225246;2981.2248815308176;3401.1837159081642;3425.160835595295;3505.2814173094966;546.3034630845704;636.3604577399684;652.1522021372197;655.8294843340766;668.85619591788;534.9411564727877;570.3127731084033;604.9909251361233;608.3034925010837;611.8623309755269;8629.78425539354;8661.30709543235;8682.293867607894;8726.69456569017;8741.103535643173;531.8776681363231;563.155267099351;586.6360711346138;633.7369991453587;647.8639485708054;4220.472440944882;4330.17726631939;4375.7155784873885;5140.306058682105;5146.166245447831;102428.97571024344;135423;151423.48576514094;164381;166345;120774;126371;127217;128688;128806.06790898144;99384;107744;110232;112451.43774281077;119422;8971.584165328617;9567.330286879851;9834.394395194671;10182.18542516582;10260.230482713678;40276;46737.76631116823;62547.6872615634;62944;63432;87728;93643;102031.26640436445;104043.686522369;104737.47631261795;13119.988858603623;13272.33638318061;13358.599242022754;13420.865791342352;13771.173729576258;14917.254137293308;14920.7761883574;14932.850671493581;15155.772663409836;15456.459023934109;11637.592684256011;13951.930240348733;13966;14006.509772157926;14848.960572760236;13726.544223781952;13859.168449893039;14092.718145637235;14233;14505.457246994465;13627.955806628688;13768.025950413996;13996.370163342877;14154.646133846736;14358.497452589108;9894.419607217114;11128.610498632508;11221.60724374643;11640.35978021217;11736.647900562997;9826.34561047181;11260;11678.6496405108;11719.589814356457;11830.82253766177 10:48:49 INFO - PROCESS | 1659 | __end_tp_report 10:48:49 INFO - PROCESS | 1659 | __start_cc_report 10:48:49 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,361 10:48:49 INFO - PROCESS | 1659 | __end_cc_report 10:48:49 INFO - PROCESS | 1659 | __startTimestamp1477331329173__endTimestamp 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | ------- Summary: start ------- 10:48:49 INFO - PROCESS | 1659 | Number of tests: 6 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5495.86 Median:5687.97 stddev:686.69 (12.1%) stddev-sans-first:663.07 10:48:49 INFO - PROCESS | 1659 | Values: 3122.3 3281.1 3339.7 3371.1 3374.6 4533.7 4701.2 4950.1 5472.5 5491.7 4354.3 4454.4 4572.4 5480.9 5567.3 5031.6 5074.8 5100.8 5126.9 5129.9 5100.2 5129.9 5168.6 5179.2 5190.8 5168.2 5175.5 5176.1 5181.9 5190.4 4489.7 4929.8 5144.9 5170.6 5175.6 4604.2 5111.3 5123.4 5130.8 5147.4 5195.2 5506.7 5563.7 5670.4 5709.8 4998.8 6070.9 6072.0 6094.4 6115.7 6046.1 6079.1 6109.9 6119.4 6134.0 5703.7 5705.4 5734.3 5758.5 5784.7 6131.1 6147.7 6162.6 6177.8 6186.2 5752.4 5754.2 5820.9 5998.1 6011.2 5019.2 5185.0 5688.0 6019.5 6032.5 6031.5 6031.9 6042.9 6051.7 6066.9 6045.9 6053.5 6061.1 6097.6 6121.0 4778.3 4810.8 4814.4 4823.1 4829.3 4282.6 4328.8 4335.0 4352.9 4391.2 4111.7 5547.0 5604.9 5627.9 5682.6 6059.6 6179.0 6200.2 6206.3 6211.1 5489.3 5638.3 5638.5 5645.3 5677.1 5230.7 5252.2 5267.4 5272.9 5363.0 6213.7 6222.5 6222.9 6242.7 6245.9 6150.5 6213.3 6214.8 6221.3 6244.7 5022.8 6119.4 6165.7 6188.3 6248.3 6018.4 6065.3 6066.5 6082.8 6098.4 5190.8 6111.9 6168.8 6212.6 6220.2 4205.5 4468.9 4584.4 4940.9 5254.7 5975.0 5987.2 5990.3 6003.9 6039.8 5158.9 5679.1 5759.0 5803.5 5862.6 4659.0 5828.0 5912.7 5989.1 5996.7 5697.6 5753.3 5757.6 5798.5 5803.7 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70015.29 Median:40518.89 stddev:152681.04 (376.8%) stddev-sans-first:153020.04 10:48:49 INFO - PROCESS | 1659 | Values: 461.2 497.7 500.1 521.7 530.7 31772.5 39160.4 40879.8 43642.3 43668.9 42333.9 42785.8 42834.6 42836.8 42841.0 8098.4 9692.2 9730.5 9763.9 9776.4 9524.0 9527.6 9576.9 9607.3 9611.5 9401.7 9610.5 9633.8 9638.0 9661.9 8546.4 9630.7 9634.5 9650.2 9678.5 9531.3 9533.2 9539.4 9606.7 9658.3 32329.8 39995.8 40194.8 40340.0 40366.4 3325.5 3403.1 3430.7 3464.2 3550.7 30167.1 30466.7 30953.0 31256.7 31755.8 3193.4 3241.3 3250.5 3272.0 3333.5 493122.0 494249.0 496643.0 496807.0 497365.0 51887.7 58840.8 61766.7 61906.7 62219.7 49756.8 56204.3 64308.7 65134.7 65662.7 13323.7 15742.5 15967.4 16082.4 16149.7 12352.3 12446.6 12451.3 12460.9 12475.0 17358.4 18855.6 18889.1 18985.3 19120.5 3160.5 6805.3 7822.4 8031.2 8086.4 819.7 879.6 889.9 978.4 1018.5 26897.7 27615.7 30024.5 30437.4 31759.3 922.6 991.8 1023.6 1078.1 1179.9 14324.9 15313.0 15862.4 15927.9 15993.0 618392.9 618862.0 622884.0 626215.9 627306.9 58374.4 58495.8 58800.7 58857.7 58873.7 1350.8 1367.8 1374.6 1388.9 1395.8 27579.0 29770.9 29806.0 30245.1 30518.7 50467.0 50629.5 50745.7 50750.0 50800.2 582176.0 587229.0 588410.0 591639.0 592000.0 25899.5 26077.1 26175.1 26340.7 26371.3 45984.8 46601.0 47651.8 48104.8 48219.0 31530.5 38480.6 39086.8 39649.0 40671.4 38815.8 39017.6 39070.4 39710.4 40157.4 38011.0 38358.4 38782.4 38792.0 38796.8 28703.1 31602.8 34046.7 34111.5 34699.1 24790.6 24894.5 25239.7 25258.5 25539.7 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35209.99 Median:24796.13 stddev:100317.97 (404.6%) stddev-sans-first:100565.20 10:48:49 INFO - PROCESS | 1659 | Values: 331.4 353.1 360.5 378.7 398.3 14947.8 20058.0 20294.0 20719.4 20855.8 19990.2 20153.3 20466.9 20603.2 20643.9 4260.1 5066.6 5636.4 5899.2 5911.6 4391.9 4954.3 5940.3 5959.6 5963.8 4851.7 4915.4 5572.3 5819.2 5903.6 4801.3 5755.5 5804.6 5831.9 5833.9 4239.8 4906.0 4946.3 5058.0 5087.8 23919.5 24758.9 24833.4 24948.8 25021.0 2178.4 2372.6 2407.9 2422.4 2428.4 13832.7 14697.2 15326.7 15433.2 15506.9 1804.4 1849.4 1996.8 2005.8 2011.9 124440.0 126950.0 127495.0 128001.7 128851.0 11178.6 11297.9 11500.9 11507.5 11653.5 14424.0 14431.6 14465.3 14476.0 14486.6 8106.3 8161.0 8249.4 8275.8 8391.4 8016.6 8021.5 8138.0 8151.5 8188.9 3671.8 3789.5 4491.7 4493.3 4589.0 1904.5 1916.9 1929.2 1935.4 1976.3 657.5 731.1 744.4 816.4 845.4 17149.0 19091.6 19844.4 20778.7 20968.3 888.5 912.7 928.3 943.3 950.2 10667.3 11357.9 11367.5 11462.1 11468.8 589622.0 593822.0 593957.0 610877.0 611739.0 105613.0 113351.9 130203.0 132445.0 133272.0 960.0 975.6 1024.4 1031.9 1033.1 19278.7 19288.6 19695.0 19697.0 19734.2 54786.5 55153.7 55827.0 56206.9 56601.7 75562.5 80539.0 81340.6 81435.0 81456.6 7734.5 7832.6 7994.1 8133.1 8249.5 8873.5 9011.6 12109.6 12343.8 12513.4 8506.0 9649.3 10370.9 10514.8 10517.2 10351.7 10577.0 10593.9 10804.4 11115.7 10634.5 10764.0 10810.5 11036.0 11239.4 7846.4 7964.8 8009.8 8205.2 8249.4 10795.0 10966.6 11037.7 11079.6 11080.4 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5478.68 Median:5016.37 stddev:3028.41 (60.4%) stddev-sans-first:3021.56 10:48:49 INFO - PROCESS | 1659 | Values: 1422.6 1554.6 1786.5 1864.3 1924.2 8028.6 8168.5 8276.2 8749.3 8765.7 8255.6 8567.1 8567.7 8663.7 8683.4 2280.2 2290.1 2318.9 2320.9 2323.8 2281.0 2316.6 2321.7 2328.5 2345.2 2288.6 2294.8 2304.2 2313.4 2317.4 2252.9 2256.6 2265.1 2266.0 2279.6 2286.9 2293.8 2293.8 2301.8 2302.6 8569.9 8709.7 8739.7 8740.0 8752.1 4651.2 4952.7 5084.2 5163.5 5261.4 3380.3 3464.3 3507.4 3575.9 3576.4 1014.3 1035.0 1043.8 1052.9 1062.0 8583.8 9893.4 9932.9 11089.7 11100.6 4421.5 4525.2 4636.4 4641.9 4643.1 9496.8 9540.0 9608.8 9615.0 9615.4 3907.7 5201.6 5213.0 5221.0 5229.2 4417.8 4560.7 4632.9 4768.0 4800.5 3803.5 3811.6 3917.8 3923.5 3938.6 1267.9 1269.7 1281.1 1289.7 1291.1 711.8 721.9 808.7 853.5 870.2 5551.5 5667.5 5804.7 5842.4 5845.6 428.0 430.9 437.4 439.2 444.4 3848.4 3892.6 3976.4 3992.4 4025.0 9853.4 10084.8 10123.5 10215.6 10216.0 9747.7 10025.2 10193.1 10199.2 10202.4 7725.6 7860.6 7862.7 7865.9 7906.1 5560.9 5571.2 5636.4 5662.7 5684.9 9252.0 9417.6 9482.2 9489.9 9524.3 9369.2 9560.6 9608.2 9823.7 9840.9 6447.2 7132.0 7192.8 7364.3 7556.2 6740.6 8244.4 8256.7 8266.3 8268.4 7209.0 7340.8 7359.3 7436.3 7447.4 7059.8 7170.4 7200.9 7262.7 7284.9 6762.8 6837.0 6873.7 6882.7 6911.5 4596.3 4883.5 4922.8 4936.8 5011.2 4146.3 4836.4 4960.7 5021.5 5030.7 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4675.10 Median:5207.98 stddev:1477.93 (28.4%) stddev-sans-first:1444.56 10:48:49 INFO - PROCESS | 1659 | Values: 266.6 303.7 304.2 311.4 317.2 4154.0 4914.9 5202.2 5344.7 5447.5 5259.6 5460.0 5512.5 5515.8 5562.6 3475.5 4183.3 4212.4 4212.6 4230.6 3660.5 4008.8 4059.1 4251.2 4263.8 3450.3 4003.8 4093.4 4178.9 4182.7 3451.9 4166.2 4171.9 4191.1 4222.9 3646.4 3649.5 3652.2 3653.2 3657.9 4394.0 4523.4 4905.4 5238.8 5374.8 4419.5 4683.4 4745.9 4758.0 4769.3 5034.0 5214.5 5433.4 5796.0 5875.5 3797.3 3906.2 3913.9 3937.9 3943.6 5540.0 5768.0 6146.7 6163.5 6234.6 3358.4 3981.1 3999.3 4010.5 4026.6 3245.7 3945.8 4007.8 4064.8 4082.5 4137.7 4702.3 4705.7 4738.5 4843.4 5026.9 5683.3 5872.2 5978.4 5983.3 1258.7 1289.2 1333.9 1353.6 1373.5 800.9 835.8 902.6 927.7 927.9 5053.8 5093.9 5333.3 5417.3 5428.4 5213.7 5601.4 5910.2 5943.7 5964.3 4480.6 5368.1 5402.5 5406.0 5492.1 2569.5 2885.7 2982.9 3185.5 3216.8 4898.8 5150.5 6393.6 6447.5 6488.5 4939.0 5984.6 6199.2 6228.8 6277.6 5265.6 6299.5 6335.4 6338.0 6349.7 5153.7 5220.9 5767.8 5919.3 6030.5 5133.9 5647.4 6005.6 6016.7 6022.9 6157.6 6381.7 6396.3 6403.9 6430.4 3527.5 3704.0 3877.8 3940.3 3980.0 4683.4 4993.5 5458.3 6035.2 6145.7 5350.5 5706.0 5794.6 5832.8 5836.0 5909.9 5942.6 5946.6 5955.4 5963.5 4822.0 5197.4 5445.4 5690.9 5868.8 5483.1 5643.4 5836.2 5841.5 5871.7 5139.5 5746.8 5761.2 5809.5 5819.5 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24837.51 Median:21443.02 stddev:39842.76 (185.8%) stddev-sans-first:39913.38 10:48:49 INFO - PROCESS | 1659 | Values: 719.7 727.4 732.2 770.0 823.3 14938.6 15979.1 15995.2 16007.1 16016.4 10740.7 11650.8 12011.6 13006.5 14365.1 874.9 877.4 902.7 906.2 909.5 519.4 718.8 904.5 906.9 907.8 909.3 930.6 936.4 951.5 962.5 948.2 949.4 951.6 952.2 953.3 693.3 705.1 707.2 707.9 708.6 14536.7 14773.2 14815.3 14826.5 14855.0 13693.5 13700.8 13716.8 13872.8 14067.2 12559.6 12660.3 12662.4 12707.2 13145.5 4409.9 4419.9 4423.1 4467.7 4640.7 111950.0 112775.0 118356.8 120815.4 121370.0 10942.7 10959.0 10979.9 10985.3 11077.1 17562.6 21110.5 21400.6 21427.4 21458.7 7620.8 7638.1 7772.2 7857.6 7910.1 4134.9 4233.9 4275.6 4275.6 4346.8 2637.6 2981.2 3401.2 3425.2 3505.3 546.3 636.4 652.2 655.8 668.9 534.9 570.3 605.0 608.3 611.9 8629.8 8661.3 8682.3 8726.7 8741.1 531.9 563.2 586.6 633.7 647.9 4220.5 4330.2 4375.7 5140.3 5146.2 102429.0 135423.0 151423.5 164381.0 166345.0 120774.0 126371.0 127217.0 128688.0 128806.1 99384.0 107744.0 110232.0 112451.4 119422.0 8971.6 9567.3 9834.4 10182.2 10260.2 40276.0 46737.8 62547.7 62944.0 63432.0 87728.0 93643.0 102031.3 104043.7 104737.5 13120.0 13272.3 13358.6 13420.9 13771.2 14917.3 14920.8 14932.9 15155.8 15456.5 11637.6 13951.9 13966.0 14006.5 14849.0 13726.5 13859.2 14092.7 14233.0 14505.5 13628.0 13768.0 13996.4 14154.6 14358.5 9894.4 11128.6 11221.6 11640.4 11736.6 9826.3 11260.0 11678.6 11719.6 11830.8 10:48:49 INFO - PROCESS | 1659 | -------- Summary: end -------- 10:48:49 INFO - PROCESS | 1659 | 10:48:49 INFO - TEST-INFO | 1659: exit 0 10:48:49 INFO - TEST-OK | dromaeo_css | took 1116377ms 10:48:49 INFO - TEST-START | kraken 10:48:49 INFO - operating with platform_type : mac_ 10:48:49 INFO - Initialising browser for kraken test... 10:48:49 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpRJpC9H/profile http://localhost:49270/getInfo.html) 10:48:52 INFO - PROCESS | 1669 | 2016-10-24 10:48:52.427 plugin-container[1670:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 10:48:52 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:52 INFO - PROCESS | 1669 | 2016-10-24 10:48:52.430 plugin-container[1670:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 10:48:52 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:52 INFO - PROCESS | 1669 | 2016-10-24 10:48:52.430 plugin-container[1670:10213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:48:52 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 10:48:52 INFO - PROCESS | 1669 | colorDepth:24 10:48:52 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 10:48:52 INFO - PROCESS | 1669 | __metrics 10:48:52 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:48:53 INFO - TEST-INFO | 1669: exit 0 10:48:53 INFO - Browser initialized. 10:48:53 INFO - Running cycle 1/1 for kraken test... 10:48:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpRJpC9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:55 INFO - PROCESS | 1671 | 2016-10-24 10:48:55.412 plugin-container[1672:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:48:55 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:55 INFO - PROCESS | 1671 | 2016-10-24 10:48:55.412 plugin-container[1672:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:48:55 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:55 INFO - PROCESS | 1671 | 2016-10-24 10:48:55.412 plugin-container[1672:10325] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:49:05 INFO - PROCESS | 1671 | RSS: Main: 237072384 10:49:05 INFO - PROCESS | 1671 | 10:49:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:09 INFO - PROCESS | 1671 | RSS: Main: 249548800 10:49:09 INFO - PROCESS | 1671 | 10:49:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 10:49:12 INFO - PROCESS | 1671 | RSS: Main: 257187840 10:49:12 INFO - PROCESS | 1671 | 10:49:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 10:49:16 INFO - PROCESS | 1671 | RSS: Main: 260046848 10:49:16 INFO - PROCESS | 1671 | 10:49:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 10:49:19 INFO - PROCESS | 1671 | RSS: Main: 262275072 10:49:19 INFO - PROCESS | 1671 | 10:49:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:49:21 INFO - PROCESS | 1671 | RSS: Main: 262750208 10:49:21 INFO - PROCESS | 1671 | 10:49:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 10:49:25 INFO - PROCESS | 1671 | RSS: Main: 263708672 10:49:25 INFO - PROCESS | 1671 | 10:49:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 10:49:28 INFO - PROCESS | 1671 | RSS: Main: 263880704 10:49:28 INFO - PROCESS | 1671 | 10:49:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 10:49:32 INFO - PROCESS | 1671 | RSS: Main: 264118272 10:49:32 INFO - PROCESS | 1671 | 10:49:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:49:34 INFO - PROCESS | 1671 | RSS: Main: 263737344 10:49:34 INFO - PROCESS | 1671 | 10:49:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:37 INFO - PROCESS | 1671 | RSS: Main: 263802880 10:49:37 INFO - PROCESS | 1671 | 10:49:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:49:40 INFO - PROCESS | 1671 | RSS: Main: 263794688 10:49:40 INFO - PROCESS | 1671 | 10:49:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:49:44 INFO - PROCESS | 1671 | RSS: Main: 264232960 10:49:44 INFO - PROCESS | 1671 | 10:49:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:49:47 INFO - PROCESS | 1671 | RSS: Main: 264138752 10:49:47 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 10:49:49 INFO - PROCESS | 1671 | RSS: Main: 265744384 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | __start_tp_report 10:49:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 10:49:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 10:49:49 INFO - PROCESS | 1671 | |i|pagename|runs| 10:49:49 INFO - PROCESS | 1671 | |0;ai-astar;104;123;142;135;109;161;99;151;96;176 10:49:49 INFO - PROCESS | 1671 | |1;audio-beat-detection;153;146;147;150;115;113;116;119;177;146 10:49:49 INFO - PROCESS | 1671 | |2;audio-dft;195;276;254;194;193;194;197;196;193;187 10:49:49 INFO - PROCESS | 1671 | |3;audio-fft;81;56;61;87;73;85;58;57;78;59 10:49:49 INFO - PROCESS | 1671 | |4;audio-oscillator;86;69;86;73;68;68;83;69;72;86 10:49:49 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;88;90;88;90;87;106;79;136;132 10:49:49 INFO - PROCESS | 1671 | |6;imaging-darkroom;143;174;142;99;98;98;101;101;100;100 10:49:49 INFO - PROCESS | 1671 | |7;imaging-desaturate;95;91;135;134;118;89;95;90;91;82 10:49:49 INFO - PROCESS | 1671 | |8;json-parse-financial;56;56;57;57;68;82;81;56;58;57 10:49:49 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;59;96;69;86;108;102;103;71;72;70 10:49:49 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;87;85;90;88;90;93;88;86;103;112 10:49:49 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;132;161;156;161;155;155;158;152;222;151 10:49:49 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;188;162;253;250;206;174;176;171;174;175 10:49:49 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;74;87;92;85;67;70;65;90;88;91 10:49:49 INFO - PROCESS | 1671 | __end_tp_report 10:49:49 INFO - PROCESS | 1671 | __start_cc_report 10:49:49 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,936 10:49:49 INFO - PROCESS | 1671 | __end_cc_report 10:49:49 INFO - PROCESS | 1671 | __startTimestamp1477331389952__endTimestamp 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | ------- Summary: start ------- 10:49:49 INFO - PROCESS | 1671 | Number of tests: 14 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:129.60 Median:156.00 stddev:27.83 (17.8%) stddev-sans-first:27.93 10:49:49 INFO - PROCESS | 1671 | Values: 104.0 123.0 142.0 135.0 109.0 161.0 99.0 151.0 96.0 176.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:146.50 stddev:21.34 (14.6%) stddev-sans-first:21.95 10:49:49 INFO - PROCESS | 1671 | Values: 153.0 146.0 147.0 150.0 115.0 113.0 116.0 119.0 177.0 146.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:207.90 Median:195.50 stddev:30.65 (15.7%) stddev-sans-first:32.16 10:49:49 INFO - PROCESS | 1671 | Values: 195.0 276.0 254.0 194.0 193.0 194.0 197.0 196.0 193.0 187.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:69.50 Median:75.50 stddev:12.55 (16.6%) stddev-sans-first:12.60 10:49:49 INFO - PROCESS | 1671 | Values: 81.0 56.0 61.0 87.0 73.0 85.0 58.0 57.0 78.0 59.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:78.00 stddev:8.16 (10.5%) stddev-sans-first:7.82 10:49:49 INFO - PROCESS | 1671 | Values: 86.0 69.0 86.0 73.0 68.0 68.0 83.0 69.0 72.0 86.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:88.00 stddev:19.82 (22.5%) stddev-sans-first:20.77 10:49:49 INFO - PROCESS | 1671 | Values: 90.0 88.0 90.0 88.0 90.0 87.0 106.0 79.0 136.0 132.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:158.50 stddev:27.22 (17.2%) stddev-sans-first:27.00 10:49:49 INFO - PROCESS | 1671 | Values: 143.0 174.0 142.0 99.0 98.0 98.0 101.0 101.0 100.0 100.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:102.00 Median:90.50 stddev:19.50 (21.5%) stddev-sans-first:20.52 10:49:49 INFO - PROCESS | 1671 | Values: 95.0 91.0 135.0 134.0 118.0 89.0 95.0 90.0 91.0 82.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:57.50 stddev:10.49 (18.2%) stddev-sans-first:10.83 10:49:49 INFO - PROCESS | 1671 | Values: 56.0 56.0 57.0 57.0 68.0 82.0 81.0 56.0 58.0 57.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:83.60 Median:70.50 stddev:17.53 (24.9%) stddev-sans-first:16.18 10:49:49 INFO - PROCESS | 1671 | Values: 59.0 96.0 69.0 86.0 108.0 102.0 103.0 71.0 72.0 70.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:88.00 stddev:8.64 (9.8%) stddev-sans-first:8.96 10:49:49 INFO - PROCESS | 1671 | Values: 87.0 85.0 90.0 88.0 90.0 93.0 88.0 86.0 103.0 112.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:157.00 stddev:23.20 (14.8%) stddev-sans-first:22.23 10:49:49 INFO - PROCESS | 1671 | Values: 132.0 161.0 156.0 161.0 155.0 155.0 158.0 152.0 222.0 151.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.90 Median:182.00 stddev:33.04 (18.2%) stddev-sans-first:34.99 10:49:49 INFO - PROCESS | 1671 | Values: 188.0 162.0 253.0 250.0 206.0 174.0 176.0 171.0 174.0 175.0 10:49:49 INFO - PROCESS | 1671 | 10:49:49 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.90 Median:87.50 stddev:10.67 (12.2%) stddev-sans-first:11.02 10:49:49 INFO - PROCESS | 1671 | Values: 74.0 87.0 92.0 85.0 67.0 70.0 65.0 90.0 88.0 91.0 10:49:49 INFO - PROCESS | 1671 | -------- Summary: end -------- 10:49:49 INFO - PROCESS | 1671 | 10:49:50 INFO - TEST-INFO | 1671: exit 0 10:49:50 INFO - TEST-OK | kraken | took 61014ms 10:49:50 INFO - SUITE-END | took 1177s 10:49:51 INFO - Completed test suite (00:19:37) 10:49:51 INFO - javascript benchmark 10:49:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7243.440409187675, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.29748306631, 3281.1469018055313, 3339.716124129449, 3371.0729549373905, 3374.611919629242, 4533.727976321423, 4701.15379231739, 4950.084234416636, 5472.50747432731, 5491.725413729312, 4354.325079612657, 4454.420925279709, 4572.359869618248, 5480.903819236149, 5567.331920169575, 5031.572316353115, 5074.847754567369, 5100.795968161287, 5126.897462050757, 5129.897402051975, 5100.20946753252, 5129.897402051956, 5168.612354073441, 5179.197224430201, 5190.844274671747, 5168.224766285069, 5175.534201921826, 5176.12005958986, 5181.870453238662, 5190.377154741436, 4489.685721999462, 4929.753512324405, 5144.948550514503, 5170.612204084689, 5175.560077393426, 4604.171249175147, 5111.284420181178, 5123.410807757118, 5130.820421285237, 5147.356580427447, 5195.168772996302, 5506.669599823983, 5563.721813909328, 5670.432956704338, 5709.77160913563, 4998.800047998076, 6070.908936365958, 6072.0, 6094.390560943914, 6115.663638499881, 6046.062860256668, 6079.148919151322, 6109.908351374733, 6119.388061193889, 6133.969330153365, 5703.743331550132, 5705.429457054262, 5734.340550836624, 5758.452946970031, 5784.652920824764, 6131.0803379492545, 6147.72335244915, 6162.599431036968, 6177.752889884444, 6186.1648677428875, 5752.36723960364, 5754.165645981309, 5820.912686309709, 5998.130271110665, 6011.188489553854, 5019.196928491424, 5185.0, 5687.971560142173, 6019.518438524908, 6032.487238584682, 6031.547633927473, 6031.9396806032255, 6042.879142417215, 6051.667158306335, 6066.878662426727, 6045.909311360333, 6053.545984051214, 6061.060535616986, 6097.603655762361, 6121.0206366981065, 4778.307145463958, 4810.831620893322, 4814.422269327703, 4823.059503396804, 4829.299751536078, 4282.550332215135, 4328.761918094535, 4335.0, 4352.9129417412105, 4391.231534481517, 4111.671066314688, 5547.001539722808, 5604.943950560524, 5627.915581266284, 5682.573806964494, 6059.6364218147055, 6178.969105154445, 6200.193974783249, 6206.348333424925, 6211.068339748984, 5489.34127904646, 5638.323401191883, 5638.492535671728, 5645.294338207724, 5677.09166533361, 5230.686158830483, 5252.1596544552685, 5267.367915850123, 5272.947270527246, 5363.03465376236, 6213.720382582795, 6222.471089957405, 6222.875542489034, 6242.687865606792, 6245.875082498415, 6150.538709596887, 6213.285472170758, 6214.844628884314, 6221.346758590283, 6244.687765611792, 5022.824201152942, 6119.350229749377, 6165.660888651168, 6188.288346840171, 6248.312685604489, 6018.428249316308, 6065.332813390435, 6066.4540191382985, 6082.787102451393, 6098.420650038239, 5190.766415511386, 6111.877762444815, 6168.784092556739, 6212.565120441706, 6220.222472190976, 4205.495340559152, 4468.949796797685, 4584.426946631671, 4940.925886111783, 5254.6847189168775, 5975.0, 5987.191729116514, 5990.251218597676, 6003.939960600338, 6039.849003774765, 5158.942416804621, 5679.091345384719, 5759.0, 5803.47768700819, 5862.648241105548, 4659.0, 5828.009238429562, 5912.704364781658, 5989.071693887455, 5996.700164991646, 5697.57268204878, 5753.280839895013, 5757.625754325955, 5798.478136967693, 5803.709814509342], "value": 5453.898199494159, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.181403009658, 497.7237633113623, 500.09982032341776, 521.6504941689069, 530.6975024236184, 31772.523412148836, 39160.41259381108, 40879.79560102199, 43642.34536481955, 43668.908277293005, 42333.94165145873, 42785.786071069604, 42834.57165428345, 42836.78581607088, 42841.0, 8098.433109682324, 9692.176165025981, 9730.464824434654, 9763.853542196837, 9776.364536305118, 9523.95238023809, 9527.571259293349, 9576.856347154799, 9607.27945404094, 9611.519424028771, 9401.670941517048, 9610.519474026305, 9633.795775528059, 9638.03619638034, 9661.90338096617, 8546.444481108709, 9630.662926797599, 9634.47010414427, 9650.227981761442, 9678.516074196263, 9531.332806703464, 9533.189678877303, 9539.379940303857, 9606.711798646053, 9658.323917325719, 32329.838350808095, 39995.800021, 40194.79902600498, 40340.0, 40366.39450408246, 3325.451300535409, 3403.081168084603, 3430.6569343065744, 3464.20323325634, 3550.7336949728874, 30167.09498715042, 30466.695333046606, 30953.0, 31256.687433125833, 31755.84122079398, 3193.3613277344625, 3241.319322942185, 3250.4799232122746, 3272.0, 3333.4666453367345, 493122.0, 494249.0, 496643.0, 496807.0, 497365.0, 51887.74056129771, 58840.823183536944, 61766.69116654478, 61906.69046654738, 62219.68890155611, 49756.75121624369, 56204.313870583945, 64308.67845660742, 65134.67432662806, 65662.67168664222, 13323.66690832737, 15742.527724168292, 15967.44113956006, 16082.43711470093, 16149.677006459806, 12352.320622365856, 12446.564370246997, 12451.315177665134, 12460.940820030217, 12475.0, 17358.44126758987, 18855.622887542446, 18889.149988250563, 18985.33551325697, 19120.521986950436, 3160.4627213373783, 6805.319468053254, 7822.413319000984, 8031.152834848018, 8086.353091752646, 819.7458787775809, 879.564615515324, 889.8709687095335, 978.3787295067683, 1018.4907546226887, 26897.731022689524, 27615.723842761316, 30024.54963175554, 30437.39125217483, 31759.25324107196, 922.6493932305681, 991.7867658453467, 1023.5752162852333, 1078.0997866781324, 1179.8525184351956, 14324.928375358057, 15313.004654697408, 15862.365505379656, 15927.920360398124, 15993.000874890638, 618392.908035466, 618862.0, 622884.0, 626215.8689206524, 627306.8634656797, 58374.4162558369, 58495.83008339809, 58800.7059964706, 58857.70571147202, 58873.70563147157, 1350.7636163671318, 1367.8290213723285, 1374.6288502104576, 1388.9305534723223, 1395.762720337545, 27579.0, 29770.85114574413, 29806.0, 30245.09264722062, 30518.694813052472, 50466.99066018732, 50629.49370506248, 50745.74627126988, 50750.0, 50800.23799642934, 582176.0, 587229.0, 588410.0, 591639.0, 592000.0399997972, 25899.482010359312, 26077.087301944342, 26175.083872064388, 26340.736592634596, 26371.340716482242, 45984.770076149405, 46601.0, 47651.808704782656, 48104.75947620379, 48219.0, 31530.527042094847, 38480.615193847705, 39086.82739517819, 39649.00877477971, 40671.38992915049, 38815.80592097021, 39017.60982390253, 39070.4139437903, 39710.404343934286, 40157.397639034854, 38011.0, 38358.42462363011, 38782.4182637255, 38792.0, 38796.80601596974, 28703.138905832857, 31602.841985789924, 34046.65953340435, 34111.48832767461, 34699.13252168716, 24790.628140578265, 24894.502109958063, 25239.747602524476, 25258.494830103664, 25539.744602553736], "value": 19743.624861326458, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.3786650031191, 353.1436267052398, 360.5061066339115, 378.6610983170061, 398.3049578485543, 14947.775783363259, 20058.0, 20294.0, 20719.378418647426, 20855.791442085574, 19990.200391984305, 20153.29463468779, 20466.897665511653, 20603.175872965065, 20643.896780516116, 4260.147970405916, 5066.594672426216, 5636.379998200195, 5899.203607512974, 5911.586188966774, 4391.86824395267, 4954.281629163769, 5940.2871655401195, 5959.582829201958, 5963.821085367446, 4851.660383773137, 4915.361003069596, 5572.3034620672415, 5819.243498345209, 5903.586748927577, 4801.2798080287885, 5755.510781583571, 5804.564657650693, 5831.912521312183, 5833.941660583382, 4239.809208585591, 4906.0, 4946.2580612908, 5057.974710126463, 5087.79648814047, 23919.52160956771, 24758.876205619035, 24833.379165520826, 24948.750512494826, 25021.0, 2178.433607262123, 2372.608519594265, 2407.855528668268, 2422.4125129931163, 2428.441458464563, 13832.654183645389, 14697.191654459, 15326.693466130615, 15433.151176685278, 15506.922465387714, 1804.3865085870736, 1849.3804575467234, 1996.800319968006, 2005.839532837384, 2011.8591698581106, 124440.0, 126950.0, 127495.0, 128001.71998279991, 128851.0, 11178.60874869392, 11297.887021129683, 11500.942495287638, 11507.45455545055, 11653.47559359814, 14424.0, 14431.639209019859, 14465.349059292357, 14476.0, 14486.565403037926, 8106.310963568045, 8160.97987751531, 8249.422540422232, 8275.793105172419, 8391.370647201484, 8016.55908925003, 8021.5187088774865, 8137.959310203411, 8151.510909345339, 8188.918110818935, 3671.7980511072146, 3789.5073640426567, 4491.663125265618, 4493.326001099796, 4589.036302376471, 1904.4762690260013, 1916.875403098794, 1929.1608150454597, 1935.4387227703808, 1976.2589029114083, 657.4773055420952, 731.1263040666458, 744.3624027140313, 816.3673153306236, 845.3913182508584, 17149.0, 19091.568132390126, 19844.404667859988, 20778.688319675217, 20968.266110686054, 888.4806980064034, 912.7124955638952, 928.2898582584194, 943.3160958305174, 950.1780959470009, 10667.253292269621, 11357.886421135683, 11367.488463019185, 11462.083033357312, 11468.770624587629, 589622.0, 593822.0, 593957.0, 610877.0, 611739.0, 105613.0, 113351.86648133578, 130203.0, 132445.0, 133272.0, 959.9808003839884, 975.5707488704948, 1024.3853687787416, 1031.9226058045526, 1033.0702367868978, 19278.71081933744, 19288.614227715647, 19695.0, 19697.0, 19734.210631574577, 54786.45213547813, 55153.72423137858, 55827.0, 56206.875862481706, 56601.71699141478, 75562.48875022579, 80539.0, 81340.59329703313, 81435.0, 81456.59271703604, 7734.497257678233, 7832.647530861011, 7994.080680721791, 8133.146019668086, 8249.515914166925, 8873.467591944504, 9011.594478248626, 12109.576164834189, 12343.753124937271, 12513.374331283581, 8506.0, 9649.324547281623, 10370.896291037296, 10514.789704206027, 10517.211209159497, 10351.741206469898, 10577.048065674126, 10593.947030265108, 10804.351738895377, 11115.666530004113, 10634.521446534769, 10764.0, 10810.513526891466, 11036.0, 11239.438028098726, 7846.353428865567, 7964.84070318602, 8009.815265530946, 8205.179482051746, 8249.422540422112, 10794.998679404274, 10966.561337546409, 11037.724056898642, 11079.61221357281, 11080.390578517936], "value": 9397.705146513727, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.5945605502432, 1554.5880341709449, 1786.4908501077196, 1864.2915692037025, 1924.1533725160918, 8028.598570071487, 8168.509889406632, 8276.17238276172, 8749.343799215061, 8765.693200737975, 8255.628496717647, 8567.100454452271, 8567.700130495434, 8663.653453861838, 8683.392162548624, 2280.1791355112146, 2290.0839664134314, 2318.9072437102495, 2320.8607483550954, 2323.7908588227056, 2280.9543809123807, 2316.5598536278053, 2321.7097862767155, 2328.4528135888054, 2345.1791872844537, 2288.553732022256, 2294.816414686821, 2304.1704986204954, 2313.421644588853, 2317.4206448387904, 2252.8986195621237, 2256.638937769957, 2265.0939624150305, 2265.9773402265932, 2279.6352583586713, 2286.8513546619415, 2293.793558579736, 2293.8050265727434, 2301.7813307735737, 2302.6085565453914, 8569.871451928226, 8709.69516066941, 8739.737807865775, 8739.956300218522, 8752.124787521198, 4651.232546629802, 4952.678075925053, 5084.186530155194, 5163.457836927106, 5261.394939581959, 3380.323935212967, 3464.2626548019566, 3507.368673638744, 3575.8927232183078, 3576.3920133577353, 1014.2747935226348, 1034.9793004139874, 1043.8121138195122, 1052.884182739898, 1062.0388548363744, 8583.828323433496, 9893.406395616214, 9932.90067099327, 11089.667309980632, 11100.5559777608, 4421.53573874745, 4525.208088584484, 4636.350910872413, 4641.883952901137, 4643.11780761661, 9496.762580935394, 9539.99830017852, 9608.807823843485, 9615.0, 9615.375000624937, 3907.7069219808627, 5201.557867581222, 5213.035588416155, 5221.034108689904, 5229.189475631283, 4417.801198945991, 4560.726356418625, 4632.930506042412, 4768.046390721869, 4800.543948324904, 3803.486529318562, 3811.5616704079384, 3917.8432862685745, 3923.5291764988383, 3938.606139386096, 1267.9366031698562, 1269.695273134441, 1281.1039661301327, 1289.6711338608595, 1291.06985057338, 711.7615598774469, 721.8991038665971, 808.7169490678216, 853.470848074198, 870.1820288928387, 5551.500364967173, 5667.489925906688, 5804.6807425592, 5842.415758424124, 5845.649261044351, 428.036916936892, 430.9224339618836, 437.42478640587524, 439.15462734237104, 444.35346570739495, 3848.3650197717625, 3892.552356479041, 3976.383660532622, 3992.4210989406374, 4025.0, 9853.408795472295, 10084.848727269096, 10123.493825308706, 10215.642452514126, 10215.978402159724, 9747.707568772948, 10025.24810639205, 10193.082622564005, 10199.184065274909, 10202.387856728472, 7725.575093369806, 7860.646270917822, 7862.685492580347, 7865.882011769828, 7906.090799558125, 5560.91658625129, 5571.192177134374, 5636.408177141505, 5662.66024038559, 5684.886302274014, 9252.0, 9417.62329506812, 9482.19401350893, 9489.908660503854, 9524.285678573997, 9369.156775890202, 9560.61757529691, 9608.231341492525, 9823.705288841345, 9840.852387214054, 6447.161868957006, 7132.037174981519, 7192.820179495555, 7364.263573642807, 7556.168821429528, 6740.595564266159, 8244.381671374551, 8256.711015114442, 8266.338692904674, 8268.421210515206, 7208.990741296117, 7340.816479588053, 7359.300866417681, 7436.256374362521, 7447.441441891771, 7059.788206353817, 7170.354668079898, 7200.927990720235, 7262.745803896838, 7284.890726639211, 6762.763303284361, 6836.983089988565, 6873.656317184221, 6882.655867206719, 6911.481638877205, 4596.264597664358, 4883.536064073907, 4922.827701030446, 4936.777844996912, 5011.223260394645, 4146.295129827876, 4836.395450568679, 4960.677555958851, 5021.5229553192385, 5030.723310217973], "value": 4351.506091629802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.6426987836299, 303.7387846452051, 304.18781852453947, 311.3586012813604, 317.2291332063086, 4153.961509622594, 4914.852554423369, 5202.219667049944, 5344.732763361831, 5447.45525447455, 5259.6055295852775, 5460.044492213867, 5512.476314750093, 5515.779368825242, 5562.554995600352, 3475.5308033415417, 4183.284853210192, 4212.35718069953, 4212.578742125794, 4230.619244268015, 3660.469231961364, 4008.799560022002, 4059.1069964607887, 4251.2135254977775, 4263.827447451945, 3450.3271861986923, 4003.819828107742, 4093.447384603085, 4178.850816025602, 4182.665386769051, 3451.8964431066984, 4166.166766646683, 4171.874843754662, 4191.140816132685, 4222.85220017301, 3646.361886669849, 3649.4525821127045, 3652.196516766307, 3653.178034942117, 3657.926841463156, 4393.978030109861, 4523.36672865799, 4905.386826646669, 5238.764255608507, 5374.785008599691, 4419.4808958893445, 4683.437987441529, 4745.928811067837, 4757.976210118927, 4769.284607308897, 5034.0, 5214.478552144793, 5433.429489903582, 5796.043652797325, 5875.500582450497, 3797.2785170817456, 3906.2187562487334, 3913.882583522499, 3937.862174823896, 3943.5859234780223, 5540.002799496089, 5767.971160144214, 6146.7233974471255, 6163.459135216196, 6234.625922444668, 3358.429067058606, 3981.124152686404, 3999.2601368746873, 4010.5187377514885, 4026.5772093929713, 3245.724113450336, 3945.822437990297, 4007.779572123503, 4064.7967601619803, 4082.510098788164, 4137.710360274812, 4702.318163866289, 4705.717656940595, 4738.502457242009, 4843.418789745253, 5026.874328141826, 5683.2895888014, 5872.207252021008, 5978.372270911578, 5983.311919129356, 1258.672745086284, 1289.2457912121326, 1333.8799508044322, 1353.5668586052368, 1373.4643489039283, 800.9399295052777, 835.7701632051113, 902.5757893789909, 927.6753136402206, 927.9189743948225, 5053.84838454847, 5093.898122037538, 5333.306670132858, 5417.349918009864, 5428.4028756836715, 5213.748700311898, 5601.439856014447, 5910.172575839386, 5943.68196403403, 5964.31410387811, 4480.551944805558, 5368.0943265141095, 5402.459754024566, 5405.999890020359, 5492.093804522289, 2569.486102779452, 2885.7447010550477, 2982.9410559251587, 3185.5380969759253, 3216.7748257621743, 4898.804047838046, 5150.510701483353, 6393.6163830170335, 6447.451966582799, 6488.513361497812, 4938.950610493921, 5984.640921544721, 6199.16311297969, 6228.781992630236, 6277.560570760094, 5265.552428043659, 6299.5275354348605, 6335.3981371769605, 6338.0, 6349.7142628580905, 5153.6907785532985, 5220.877511335081, 5767.769289228384, 5919.348871624032, 6030.517558595214, 5133.8716532087, 5647.378788333198, 6005.579609427473, 6016.669083200466, 6022.8795424090395, 6157.599756015845, 6381.712822923072, 6396.32838551964, 6403.935960640334, 6430.3891130342545, 3527.5237842891906, 3703.9814800925824, 3877.7673339598587, 3940.2907476654477, 3980.0099950024987, 4683.437987441529, 4993.4756850530175, 5458.307924543405, 6035.245594300713, 6145.723442445012, 5350.48527192219, 5705.971470142623, 5794.63826000881, 5832.804275123504, 5836.0, 5909.91135132982, 5942.554308426799, 5946.613470124428, 5955.434233747787, 5963.522918166623, 4822.0, 5197.350331208599, 5445.40100588927, 5690.914636280542, 5868.794592189253, 5483.122700367922, 5643.407442218508, 5836.227791799031, 5841.532677385714, 5871.6770577618645, 5139.537441630271, 5746.7701291949525, 5761.193432919311, 5809.535237180931, 5819.5053420459735], "value": 4198.93222365526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.6905330707796, 727.3599232675246, 732.1945859554487, 770.0259172147234, 823.267292110022, 14938.626534336647, 15979.121148336837, 15995.200239988013, 16007.119608421532, 16016.359345626175, 10740.677779666603, 11650.82523762142, 12011.639650810468, 13006.47974081038, 14365.138091714478, 874.8687696845492, 877.4252864373846, 902.747230775384, 906.1708536688922, 909.5315912305168, 519.3508114856411, 718.7879575525207, 904.4708845325459, 906.9229115525188, 907.8456662367413, 909.2680392284209, 930.5812384427, 936.4194199596226, 951.538503825644, 962.523550842334, 948.1892981500821, 949.378157306965, 951.600327862296, 952.2286947572488, 953.3183773601838, 693.2928413018693, 705.1362081450234, 707.2114592229627, 707.8938159276098, 708.5819366573746, 14536.709265814625, 14773.187474688888, 14815.259237038214, 14826.481073162495, 14855.0, 13693.520726774515, 13700.794488082687, 13716.794248086288, 13872.791908121386, 14067.226302553356, 12559.612110592245, 12660.303683297416, 12662.36688165588, 12707.237565746085, 13145.539906103337, 4409.9118017639785, 4419.9337009944875, 4423.12366986312, 4467.7319360838455, 4640.67515273931, 111950.0, 112775.0, 118356.8164318363, 120815.3959230207, 121370.0, 10942.671719848417, 10959.0, 10979.945100274448, 10985.340879547253, 11077.11383089351, 17562.64874702499, 21110.47223819417, 21400.571988560452, 21427.357179284645, 21458.678119828215, 7620.825225368294, 7638.083429988437, 7772.222777722295, 7857.646405911748, 7910.090339611018, 4134.91730165395, 4233.872983810491, 4275.5724427557, 4275.636570891509, 4346.826126954887, 2637.551616225246, 2981.2248815308176, 3401.1837159081642, 3425.160835595295, 3505.2814173094966, 546.3034630845704, 636.3604577399684, 652.1522021372197, 655.8294843340766, 668.85619591788, 534.9411564727877, 570.3127731084033, 604.9909251361233, 608.3034925010837, 611.8623309755269, 8629.78425539354, 8661.30709543235, 8682.293867607894, 8726.69456569017, 8741.103535643173, 531.8776681363231, 563.155267099351, 586.6360711346138, 633.7369991453587, 647.8639485708054, 4220.472440944882, 4330.17726631939, 4375.7155784873885, 5140.306058682105, 5146.166245447831, 102428.97571024344, 135423.0, 151423.48576514094, 164381.0, 166345.0, 120774.0, 126371.0, 127217.0, 128688.0, 128806.06790898144, 99384.0, 107744.0, 110232.0, 112451.43774281077, 119422.0, 8971.584165328617, 9567.330286879851, 9834.394395194671, 10182.18542516582, 10260.230482713678, 40276.0, 46737.76631116823, 62547.6872615634, 62944.0, 63432.0, 87728.0, 93643.0, 102031.26640436445, 104043.686522369, 104737.47631261795, 13119.988858603623, 13272.33638318061, 13358.599242022754, 13420.865791342352, 13771.173729576258, 14917.254137293308, 14920.7761883574, 14932.850671493581, 15155.772663409836, 15456.459023934109, 11637.592684256011, 13951.930240348733, 13966.0, 14006.509772157926, 14848.960572760236, 13726.544223781952, 13859.168449893039, 14092.718145637235, 14233.0, 14505.457246994465, 13627.955806628688, 13768.025950413996, 13996.370163342877, 14154.646133846736, 14358.497452589108, 9894.419607217114, 11128.610498632508, 11221.60724374643, 11640.35978021217, 11736.647900562997, 9826.34561047181, 11260.0, 11678.6496405108, 11719.589814356457, 11830.82253766177], "value": 7810.669030334259, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 123.0, 142.0, 135.0, 109.0, 161.0, 99.0, 151.0, 96.0, 176.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 146.0, 147.0, 150.0, 115.0, 113.0, 116.0, 119.0, 177.0, 146.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 276.0, 254.0, 194.0, 193.0, 194.0, 197.0, 196.0, 193.0, 187.0], "value": 207.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 56.0, 61.0, 87.0, 73.0, 85.0, 58.0, 57.0, 78.0, 59.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 86.0, 73.0, 68.0, 68.0, 83.0, 69.0, 72.0, 86.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 90.0, 87.0, 106.0, 79.0, 136.0, 132.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 174.0, 142.0, 99.0, 98.0, 98.0, 101.0, 101.0, 100.0, 100.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 135.0, 134.0, 118.0, 89.0, 95.0, 90.0, 91.0, 82.0], "value": 102.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 57.0, 68.0, 82.0, 81.0, 56.0, 58.0, 57.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 96.0, 69.0, 86.0, 108.0, 102.0, 103.0, 71.0, 72.0, 70.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 90.0, 88.0, 90.0, 93.0, 88.0, 86.0, 103.0, 112.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 161.0, 156.0, 161.0, 155.0, 155.0, 158.0, 152.0, 222.0, 151.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 162.0, 253.0, 250.0, 206.0, 174.0, 176.0, 171.0, 174.0, 175.0], "value": 192.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 87.0, 92.0, 85.0, 67.0, 70.0, 65.0, 90.0, 88.0, 91.0], "value": 80.9, "unit": "score"}]}]} 10:49:51 INFO - Return code: 0 10:49:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:49:51 ERROR - # TBPL SUCCESS # 10:49:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:51 INFO - 2016-10-24 10:49:51.249 screenresolution[1676:10721] starting screenresolution argv=screenresolution get 10:49:51 INFO - 2016-10-24 10:49:51.251 screenresolution[1676:10721] Display 0: 1600x1200x32@60 10:49:51 INFO - 2016-10-24 10:49:51.256 screenresolution[1677:10725] starting screenresolution argv=screenresolution list 10:49:51 INFO - Available Modes on Display 0 10:49:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:51 INFO - Intel Iris: 10:49:51 INFO - Chipset Model: Intel Iris 10:49:51 INFO - Type: GPU 10:49:51 INFO - Bus: Built-In 10:49:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:51 INFO - Vendor: Intel (0x8086) 10:49:51 INFO - Device ID: 0x0a2e 10:49:51 INFO - Revision ID: 0x0009 10:49:51 INFO - Displays: 10:49:51 INFO - Display: 10:49:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:51 INFO - Display Serial Number: 1600x1200 60 10:49:51 INFO - Main Display: Yes 10:49:51 INFO - Mirror: Off 10:49:51 INFO - Online: Yes 10:49:51 INFO - Rotation: Supported 10:49:51 INFO - Return code: 0 10:49:51 INFO - Running post-action listener: _resource_record_post_action 10:49:51 INFO - [mozharness: 2016-10-24 17:49:51.492195Z] Finished run-tests step (success) 10:49:51 INFO - Running post-run listener: _resource_record_post_run 10:49:51 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38455808; Write bytes: 712509952; Read time: 246; Write time: 2024 10:49:51 INFO - TinderboxPrint: CPU usage
54.6% 10:49:51 INFO - TinderboxPrint: I/O read bytes / time
38,455,808 / 246 10:49:51 INFO - TinderboxPrint: I/O write bytes / time
712,509,952 / 2,024 10:49:51 INFO - TinderboxPrint: CPU idle
2,205.1 (45.4%) 10:49:51 INFO - TinderboxPrint: CPU system
988.9 (20.4%) 10:49:51 INFO - TinderboxPrint: CPU user
1,659.7 (34.2%) 10:49:51 INFO - TinderboxPrint: Swap in / out
443,531,264 / 0 10:49:51 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392628736; Write bytes: 385417216; Read time: 29328; Write time: 801 10:49:51 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32158208; Write bytes: 301197824; Read time: 188; Write time: 1158 10:49:51 INFO - Running post-run listener: _upload_blobber_files 10:49:51 INFO - Blob upload gear active. 10:49:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:49:51 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 10:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:49:52 INFO - (blobuploader) - INFO - Open directory for files ... 10:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:53 INFO - (blobuploader) - INFO - Done attempting. 10:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:54 INFO - (blobuploader) - INFO - Done attempting. 10:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:56 INFO - (blobuploader) - INFO - Done attempting. 10:49:56 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:56 INFO - Return code: 0 10:49:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:49:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:49:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2244e645e03418486f11a338eb4e609c77fbb45fb5fbf909c25c950f33c7996a73454f21334254983c35cfaff234b6b3d9c0f868004c19844c8fd42b637589d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29b08be72b0b954d806e74f01c14ab544bdbe083088ebe0ae11c80c0022d7dd1574027434d73a9e3ca9a766f2d671e56c1ddec4af3d56963c5ca587a8b7295ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba0ba0c4940eaa69244953ebd1605d6d17bce14994a70737b595b07c15d0d43759a14b66f3ca2c9cfc8530476ec9adb8bac15602e22af8d97198c56a7470d44a"} 10:49:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:49:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:49:56 INFO - Contents: 10:49:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2244e645e03418486f11a338eb4e609c77fbb45fb5fbf909c25c950f33c7996a73454f21334254983c35cfaff234b6b3d9c0f868004c19844c8fd42b637589d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29b08be72b0b954d806e74f01c14ab544bdbe083088ebe0ae11c80c0022d7dd1574027434d73a9e3ca9a766f2d671e56c1ddec4af3d56963c5ca587a8b7295ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba0ba0c4940eaa69244953ebd1605d6d17bce14994a70737b595b07c15d0d43759a14b66f3ca2c9cfc8530476ec9adb8bac15602e22af8d97198c56a7470d44a"} 10:49:56 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:56 INFO - Copying logs to upload dir... 10:49:56 INFO - mkdir: /builds/slave/test/build/upload/logs 10:49:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.430948 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-24 10:49:56.908666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 10:49:56.911932) ========= 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.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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/Firefox/sha512/c2244e645e03418486f11a338eb4e609c77fbb45fb5fbf909c25c950f33c7996a73454f21334254983c35cfaff234b6b3d9c0f868004c19844c8fd42b637589d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29b08be72b0b954d806e74f01c14ab544bdbe083088ebe0ae11c80c0022d7dd1574027434d73a9e3ca9a766f2d671e56c1ddec4af3d56963c5ca587a8b7295ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba0ba0c4940eaa69244953ebd1605d6d17bce14994a70737b595b07c15d0d43759a14b66f3ca2c9cfc8530476ec9adb8bac15602e22af8d97198c56a7470d44a"} build_url:https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010985 build_url: 'https://queue.taskcluster.net/v1/task/C38mb7ZeTK21VvE0ATjK2Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2244e645e03418486f11a338eb4e609c77fbb45fb5fbf909c25c950f33c7996a73454f21334254983c35cfaff234b6b3d9c0f868004c19844c8fd42b637589d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29b08be72b0b954d806e74f01c14ab544bdbe083088ebe0ae11c80c0022d7dd1574027434d73a9e3ca9a766f2d671e56c1ddec4af3d56963c5ca587a8b7295ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba0ba0c4940eaa69244953ebd1605d6d17bce14994a70737b595b07c15d0d43759a14b66f3ca2c9cfc8530476ec9adb8bac15602e22af8d97198c56a7470d44a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 10:49:56.945360) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:49:56.945745) ========= 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.1FovEPTmFs/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.VUFotbaHOY/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.005062 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 10:49:56.994332) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 10:49:56.994663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 10:49:56.995010) ========= ========= Total master_lag: 0.19 =========