builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0217 starttime: 1477250309.8 results: success (0) buildid: 20161023100823 builduid: e30fee92a32b49889e575a1b31d6ee2c revision: 3d0ad7bb854d7b03609f6acecda4382b14f56e0b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.802067) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.802488) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.802808) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.829514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.829809) ========= 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.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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-23 12:18:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-10-23 12:18:30 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137072 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.989061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:29.989378) ========= 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.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.024524 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:30.037091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-23 12:18:30.037407) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d0ad7bb854d7b03609f6acecda4382b14f56e0b --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 3d0ad7bb854d7b03609f6acecda4382b14f56e0b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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-23 12:18:30,161 truncating revision to first 12 chars 2016-10-23 12:18:30,161 Setting DEBUG logging. 2016-10-23 12:18:30,161 attempt 1/10 2016-10-23 12:18:30,161 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d0ad7bb854d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-23 12:18:31,989 unpacking tar archive at: mozilla-central-3d0ad7bb854d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.081119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-23 12:18:32.143074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:32.143404) ========= script_repo_revision: 3d0ad7bb854d7b03609f6acecda4382b14f56e0b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:32.143808) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:32.144083) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 12:18:32.164960) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-23 12:18:32.165235) ========= /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.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:18:32 INFO - MultiFileLogger online at 20161023 12:18:32 in /builds/slave/test 12:18:32 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 12:18:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:18:32 INFO - {'append_to_log': False, 12:18:32 INFO - 'base_work_dir': '/builds/slave/test', 12:18:32 INFO - 'blob_upload_branch': 'Firefox', 12:18:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:18:32 INFO - 'branch': 'Firefox', 12:18:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:18:32 INFO - 'config_files': ('talos/mac_config.py',), 12:18:32 INFO - 'default_actions': ('clobber', 12:18:32 INFO - 'read-buildbot-config', 12:18:32 INFO - 'download-and-extract', 12:18:32 INFO - 'populate-webroot', 12:18:32 INFO - 'create-virtualenv', 12:18:32 INFO - 'install', 12:18:32 INFO - 'run-tests'), 12:18:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:18:32 INFO - 'download_minidump_stackwalk': True, 12:18:32 INFO - 'download_symbols': 'ondemand', 12:18:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:18:32 INFO - 'tooltool.py': '/tools/tooltool.py', 12:18:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:18:32 INFO - '/tools/misc-python/virtualenv.py')}, 12:18:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:18:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:18:32 INFO - 'installer_path': 'installer.exe', 12:18:32 INFO - 'log_level': 'info', 12:18:32 INFO - 'log_name': 'talos', 12:18:32 INFO - 'log_to_console': True, 12:18:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:18:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:18:32 INFO - 'opt_config_files': (), 12:18:32 INFO - 'pip_index': False, 12:18:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:32 INFO - 'cmd': ('bash', 12:18:32 INFO - '-c', 12:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:32 INFO - 'enabled': True, 12:18:32 INFO - 'halt_on_failure': False, 12:18:32 INFO - 'name': 'check_screen_resolution'},), 12:18:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:32 INFO - 'cmd': ('bash', 12:18:32 INFO - '-c', 12:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:32 INFO - 'enabled': True, 12:18:32 INFO - 'halt_on_failure': False, 12:18:32 INFO - 'name': 'check_screen_resolution'},), 12:18:32 INFO - 'run_cmd_checks_enabled': True, 12:18:32 INFO - 'sps_profile': False, 12:18:32 INFO - 'sps_profile_interval': 0, 12:18:32 INFO - 'suite': 'dromaeojs-e10s', 12:18:32 INFO - 'system_bits': '32', 12:18:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:18:32 INFO - 'title': 't-yosemite-r7-0217', 12:18:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:18:32 INFO - 'use_talos_json': True, 12:18:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:18:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:18:32 INFO - 'work_dir': 'build'} 12:18:32 INFO - [mozharness: 2016-10-23 19:18:32.403503Z] Running clobber step. 12:18:32 INFO - Running pre-action listener: _resource_record_pre_action 12:18:32 INFO - Running main action method: clobber 12:18:32 INFO - rmtree: /builds/slave/test/build 12:18:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:18:34 INFO - Running post-action listener: _resource_record_post_action 12:18:34 INFO - [mozharness: 2016-10-23 19:18:34.628033Z] Finished clobber step (success) 12:18:34 INFO - [mozharness: 2016-10-23 19:18:34.628168Z] Running read-buildbot-config step. 12:18:34 INFO - Running pre-action listener: _resource_record_pre_action 12:18:34 INFO - Running main action method: read_buildbot_config 12:18:34 INFO - Using buildbot properties: 12:18:34 INFO - { 12:18:34 INFO - "project": "", 12:18:34 INFO - "product": "firefox", 12:18:34 INFO - "script_repo_revision": "production", 12:18:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:18:34 INFO - "repository": "", 12:18:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:18:34 INFO - "buildid": "20161023100823", 12:18:34 INFO - "pgo_build": "False", 12:18:34 INFO - "basedir": "/builds/slave/test", 12:18:34 INFO - "buildnumber": 149, 12:18:34 INFO - "slavename": "t-yosemite-r7-0217", 12:18:34 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:18:34 INFO - "platform": "yosemite_r7", 12:18:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:18:34 INFO - "branch": "mozilla-central", 12:18:34 INFO - "repo_path": "mozilla-central", 12:18:34 INFO - "revision": "3d0ad7bb854d7b03609f6acecda4382b14f56e0b", 12:18:34 INFO - "stage_platform": "macosx64", 12:18:34 INFO - "builduid": "e30fee92a32b49889e575a1b31d6ee2c", 12:18:34 INFO - "slavebuilddir": "test" 12:18:34 INFO - } 12:18:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:18:34 INFO - Running post-action listener: _resource_record_post_action 12:18:34 INFO - [mozharness: 2016-10-23 19:18:34.631513Z] Finished read-buildbot-config step (success) 12:18:34 INFO - [mozharness: 2016-10-23 19:18:34.631595Z] Running download-and-extract step. 12:18:34 INFO - Running pre-action listener: _resource_record_pre_action 12:18:34 INFO - Running main action method: download_and_extract 12:18:34 INFO - mkdir: /builds/slave/test/build/tests 12:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:34 INFO - trying https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:18:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/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 12:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/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 12:18:35 INFO - Downloaded 1199 bytes. 12:18:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:18:35 INFO - Using the following test package requirements: 12:18:35 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:18:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:18:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:18:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'jsshell-mac.zip'], 12:18:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:18:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:18:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:18:35 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:18:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:18:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:18:35 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:18:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:18:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:18:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:18:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:18:36 INFO - Http code: 200 12:18:36 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 12:18:36 INFO - x-amz-cf-id: eax7eQvPBkoxdNYFeqXAWRgyzgzuwd2RFU6zCLL8jLoxJyfAz-dE-Q== 12:18:36 INFO - x-amz-version-id: WRwnIzohTO0_W7gx2PO7bZSW5FlnApG. 12:18:36 INFO - Expected file size: 16424921 12:18:36 INFO - Obtained file size: 16424921 12:18:44 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 12:18:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:18:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:18:44 INFO - Fetch https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:18:44 INFO - Http code: 200 12:18:44 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 12:18:44 INFO - x-amz-cf-id: texkmheOZGX2oiTZlayT-_nW-6u-hxK9xHWSbNNwvNVGUObzz95P6g== 12:18:44 INFO - x-amz-version-id: WRwnIzohTO0_W7gx2PO7bZSW5FlnApG. 12:18:44 INFO - Expected file size: 16424921 12:18:44 INFO - Obtained file size: 16424921 12:18:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:18:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:18:51 INFO - Fetch https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:18:51 INFO - Http code: 200 12:18:51 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 12:18:51 INFO - x-amz-cf-id: udlj7ZM0dwD_gzdK8J1nx41rx_SKOWUdO2tzhu97SF3Go7leGOXupQ== 12:18:51 INFO - x-amz-version-id: eeBxahJ55Y8iUZc6VTIde4.Yml1yNFhw 12:18:52 INFO - Expected file size: 14409028 12:18:52 INFO - Obtained file size: 14409028 12:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:52 INFO - trying https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:18:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/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 12:18:55 INFO - Downloaded 111231581 bytes. 12:18:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:18:55 INFO - mkdir: /builds/slave/test/properties 12:18:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:18:55 INFO - Writing to file /builds/slave/test/properties/build_url 12:18:55 INFO - Contents: 12:18:55 INFO - build_url:https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:18:56 INFO - Running post-action listener: _resource_record_post_action 12:18:56 INFO - Running post-action listener: set_extra_try_arguments 12:18:56 INFO - [mozharness: 2016-10-23 19:18:56.102501Z] Finished download-and-extract step (success) 12:18:56 INFO - [mozharness: 2016-10-23 19:18:56.102657Z] Running populate-webroot step. 12:18:56 INFO - Running pre-action listener: _resource_record_pre_action 12:18:56 INFO - Running main action method: populate_webroot 12:18:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:18:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:18:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:18:56 INFO - '--tppagecycles', 12:18:56 INFO - '7'], 12:18:56 INFO - 'tests': ['tsvgm']}}, 12:18:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:18:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:18:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:18:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:18:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['damp', 'tps']}, 12:18:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'tests': ['damp', 'tps']}, 12:18:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['dromaeo_dom']}, 12:18:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:18:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['basic_compositor_video']}, 12:18:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:18:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['a11yr', 12:18:56 INFO - 'ts_paint', 12:18:56 INFO - 'tpaint', 12:18:56 INFO - 'sessionrestore', 12:18:56 INFO - 'sessionrestore_no_auto_restore', 12:18:56 INFO - 'tabpaint']}, 12:18:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['tsvgx', 12:18:56 INFO - 'tsvgr_opacity', 12:18:56 INFO - 'tart', 12:18:56 INFO - 'tscrollx', 12:18:56 INFO - 'cart']}, 12:18:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:18:56 INFO - 'tsvgr_opacity', 12:18:56 INFO - 'tart', 12:18:56 INFO - 'tscrollx', 12:18:56 INFO - 'cart']}, 12:18:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'talos_options': ['--disable-e10s'], 12:18:56 INFO - 'tests': ['tp5o']}, 12:18:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'tests': ['tp5o']}, 12:18:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'talos_options': ['--disable-e10s', 12:18:56 INFO - '--xperf_path', 12:18:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:56 INFO - 'tests': ['tp5n']}, 12:18:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:18:56 INFO - 'talos_options': ['--xperf_path', 12:18:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:56 INFO - 'tests': ['tp5n']}}, 12:18:56 INFO - 'talos.zip': {'path': '', 12:18:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:18:56 INFO - Running post-action listener: _resource_record_post_action 12:18:56 INFO - [mozharness: 2016-10-23 19:18:56.121439Z] Finished populate-webroot step (success) 12:18:56 INFO - [mozharness: 2016-10-23 19:18:56.121551Z] Running create-virtualenv step. 12:18:56 INFO - Running pre-action listener: _resource_record_pre_action 12:18:56 INFO - Running main action method: create_virtualenv 12:18:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:18:56 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 12:18:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:18:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:18:56 INFO - Using real prefix '/tools/python27' 12:18:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:18:56 INFO - Installing distribute.............................................................................................................................................................................................done. 12:18:59 INFO - Installing pip.................done. 12:18:59 INFO - Return code: 0 12:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:19:00 INFO - Reading from file tmpfile_stdout 12:19:00 INFO - Output received: 12:19:00 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) 12:19:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:19:00 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')]} 12:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:00 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')]} 12:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:00 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:00 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 12:19:00 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 12:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:00 INFO - 'HOME': '/Users/cltbld', 12:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:00 INFO - 'LOGNAME': 'cltbld', 12:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:00 INFO - 'MOZ_NO_REMOTE': '1', 12:19:00 INFO - 'NO_EM_RESTART': '1', 12:19:00 INFO - 'PAGER': '/bin/cat', 12:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:00 INFO - 'PWD': '/builds/slave/test', 12:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:00 INFO - 'SHELL': '/bin/bash', 12:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:00 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:00 INFO - 'USER': 'cltbld', 12:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:00 INFO - 'XPC_FLAGS': '0x0', 12:19:00 INFO - 'XPC_SERVICE_NAME': '0', 12:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:00 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 12:19:00 INFO - Cleaning up... 12:19:00 INFO - Return code: 0 12:19:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:19:00 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')]} 12:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:00 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')]} 12:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:00 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:00 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 12:19:00 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 12:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:00 INFO - 'HOME': '/Users/cltbld', 12:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:00 INFO - 'LOGNAME': 'cltbld', 12:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:00 INFO - 'MOZ_NO_REMOTE': '1', 12:19:00 INFO - 'NO_EM_RESTART': '1', 12:19:00 INFO - 'PAGER': '/bin/cat', 12:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:00 INFO - 'PWD': '/builds/slave/test', 12:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:00 INFO - 'SHELL': '/bin/bash', 12:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:00 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:00 INFO - 'USER': 'cltbld', 12:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:00 INFO - 'XPC_FLAGS': '0x0', 12:19:00 INFO - 'XPC_SERVICE_NAME': '0', 12:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:00 INFO - Downloading/unpacking psutil>=3.1.1 12:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:19:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:03 INFO - Installing collected packages: psutil 12:19:03 INFO - Running setup.py install for psutil 12:19:04 INFO - building 'psutil._psutil_osx' extension 12:19:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:19:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:19:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:19:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:19:04 INFO - building 'psutil._psutil_posix' extension 12:19:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:19:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:19:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:19:04 INFO - ^ 12:19:04 INFO - 1 warning generated. 12:19:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:19:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:05 INFO - Successfully installed psutil 12:19:05 INFO - Cleaning up... 12:19:05 INFO - Return code: 0 12:19:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:19:05 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')]} 12:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:05 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')]} 12:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:05 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:05 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 12:19:05 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 12:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:05 INFO - 'HOME': '/Users/cltbld', 12:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:05 INFO - 'LOGNAME': 'cltbld', 12:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:05 INFO - 'MOZ_NO_REMOTE': '1', 12:19:05 INFO - 'NO_EM_RESTART': '1', 12:19:05 INFO - 'PAGER': '/bin/cat', 12:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:05 INFO - 'PWD': '/builds/slave/test', 12:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:05 INFO - 'SHELL': '/bin/bash', 12:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:05 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:05 INFO - 'USER': 'cltbld', 12:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:05 INFO - 'XPC_FLAGS': '0x0', 12:19:05 INFO - 'XPC_SERVICE_NAME': '0', 12:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:19:08 INFO - Installing collected packages: mozsystemmonitor 12:19:08 INFO - Running setup.py install for mozsystemmonitor 12:19:08 INFO - Successfully installed mozsystemmonitor 12:19:08 INFO - Cleaning up... 12:19:08 INFO - Return code: 0 12:19:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:19: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')]} 12:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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')]} 12:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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', '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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:19: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 jsonschema==2.5.1 12:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:08 INFO - 'HOME': '/Users/cltbld', 12:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:08 INFO - 'LOGNAME': 'cltbld', 12:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:08 INFO - 'MOZ_NO_REMOTE': '1', 12:19:08 INFO - 'NO_EM_RESTART': '1', 12:19:08 INFO - 'PAGER': '/bin/cat', 12:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:08 INFO - 'PWD': '/builds/slave/test', 12:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:08 INFO - 'SHELL': '/bin/bash', 12:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:08 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:08 INFO - 'USER': 'cltbld', 12:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:08 INFO - 'XPC_FLAGS': '0x0', 12:19:08 INFO - 'XPC_SERVICE_NAME': '0', 12:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:08 INFO - Downloading/unpacking jsonschema==2.5.1 12:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:19:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:11 INFO - Downloading functools32-3.2.3-2.tar.gz 12:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:19:11 INFO - warning: no files found matching '*.txt' 12:19:11 INFO - no previously-included directories found matching 'build' 12:19:11 INFO - no previously-included directories found matching 'dist' 12:19:11 INFO - no previously-included directories found matching '.git*' 12:19:11 INFO - Installing collected packages: jsonschema, functools32 12:19:11 INFO - Running setup.py install for functools32 12:19:11 INFO - warning: no files found matching '*.txt' 12:19:11 INFO - no previously-included directories found matching 'build' 12:19:11 INFO - no previously-included directories found matching 'dist' 12:19:11 INFO - no previously-included directories found matching '.git*' 12:19:11 INFO - Successfully installed jsonschema functools32 12:19:11 INFO - Cleaning up... 12:19:11 INFO - Return code: 0 12:19:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:11 INFO - 'HOME': '/Users/cltbld', 12:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:11 INFO - 'LOGNAME': 'cltbld', 12:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:11 INFO - 'MOZ_NO_REMOTE': '1', 12:19:11 INFO - 'NO_EM_RESTART': '1', 12:19:11 INFO - 'PAGER': '/bin/cat', 12:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:11 INFO - 'PWD': '/builds/slave/test', 12:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:11 INFO - 'SHELL': '/bin/bash', 12:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:11 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:11 INFO - 'USER': 'cltbld', 12:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:11 INFO - 'XPC_FLAGS': '0x0', 12:19:11 INFO - 'XPC_SERVICE_NAME': '0', 12:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:19:12 INFO - Cleaning up... 12:19:12 INFO - Return code: 0 12:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:12 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:12 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 12:19:12 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 12:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:12 INFO - 'HOME': '/Users/cltbld', 12:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:12 INFO - 'LOGNAME': 'cltbld', 12:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:12 INFO - 'MOZ_NO_REMOTE': '1', 12:19:12 INFO - 'NO_EM_RESTART': '1', 12:19:12 INFO - 'PAGER': '/bin/cat', 12:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:12 INFO - 'PWD': '/builds/slave/test', 12:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:12 INFO - 'SHELL': '/bin/bash', 12:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:12 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:12 INFO - 'USER': 'cltbld', 12:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:12 INFO - 'XPC_FLAGS': '0x0', 12:19:12 INFO - 'XPC_SERVICE_NAME': '0', 12:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:12 INFO - Downloading/unpacking blobuploader==1.2.4 12:19:12 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 12:19:12 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 12:19:12 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 12:19:12 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 12:19:12 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 12:19:12 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 12:19:14 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:19:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:16 INFO - Downloading docopt-0.6.1.tar.gz 12:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:19:16 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:16 INFO - Running setup.py install for blobuploader 12:19:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:19:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:19:16 INFO - Running setup.py install for requests 12:19:17 INFO - Running setup.py install for docopt 12:19:17 INFO - Successfully installed blobuploader requests docopt 12:19:17 INFO - Cleaning up... 12:19:17 INFO - Return code: 0 12:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19: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')]} 12:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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')]} 12:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:17 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:17 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 12:19:17 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 12:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:17 INFO - 'HOME': '/Users/cltbld', 12:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:17 INFO - 'LOGNAME': 'cltbld', 12:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:17 INFO - 'MOZ_NO_REMOTE': '1', 12:19:17 INFO - 'NO_EM_RESTART': '1', 12:19:17 INFO - 'PAGER': '/bin/cat', 12:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:17 INFO - 'PWD': '/builds/slave/test', 12:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:17 INFO - 'SHELL': '/bin/bash', 12:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:17 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:17 INFO - 'USER': 'cltbld', 12:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:17 INFO - 'XPC_FLAGS': '0x0', 12:19:17 INFO - 'XPC_SERVICE_NAME': '0', 12:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:17 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Y4dmJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:17 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-x3WJgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:17 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-4nU28b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:17 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-62YOLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:17 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-2Uxscj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-uq5NXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-zrdqDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-fp_MZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-rNo07j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-q8kHaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-L1blUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-aBnSyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Knxoav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-9ROjWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-iAh06J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Surome-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-RuAlfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:19 INFO - Running setup.py install for manifestparser 12:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:19:19 INFO - Running setup.py install for mozcrash 12:19:19 INFO - Running setup.py install for mozdebug 12:19:19 INFO - Running setup.py install for mozdevice 12:19:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:19:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:19:19 INFO - Running setup.py install for mozfile 12:19:20 INFO - Running setup.py install for mozhttpd 12:19:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Running setup.py install for mozinfo 12:19:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Running setup.py install for mozInstall 12:19:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Running setup.py install for mozleak 12:19:20 INFO - Running setup.py install for mozlog 12:19:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Running setup.py install for moznetwork 12:19:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:19:20 INFO - Running setup.py install for mozprocess 12:19:21 INFO - Running setup.py install for mozprofile 12:19:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:19:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:19:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:19:21 INFO - Running setup.py install for mozrunner 12:19:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:21 INFO - Running setup.py install for mozscreenshot 12:19:21 INFO - Running setup.py install for moztest 12:19:21 INFO - Running setup.py install for mozversion 12:19:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:21 INFO - Cleaning up... 12:19:21 INFO - Return code: 0 12:19:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19: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')]} 12:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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')]} 12:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:21 INFO - 'HOME': '/Users/cltbld', 12:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:21 INFO - 'LOGNAME': 'cltbld', 12:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:21 INFO - 'MOZ_NO_REMOTE': '1', 12:19:21 INFO - 'NO_EM_RESTART': '1', 12:19:21 INFO - 'PAGER': '/bin/cat', 12:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:21 INFO - 'PWD': '/builds/slave/test', 12:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:21 INFO - 'SHELL': '/bin/bash', 12:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:21 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:21 INFO - 'USER': 'cltbld', 12:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:21 INFO - 'XPC_FLAGS': '0x0', 12:19:21 INFO - 'XPC_SERVICE_NAME': '0', 12:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-3D9qJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:22 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)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-_f_9yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:22 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)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-KYSmMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:22 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)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-3TwlOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:22 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)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-FKEM_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:22 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)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-25xVH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-tg1kuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-72ARdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:22 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-J7vAk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-4DP9_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-5HWdg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-RHc1_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-c3KZUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-gmSIUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:23 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)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-xPZsZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-TTGl7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:23 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-mwZ7zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:26 INFO - Downloading blessings-1.6.tar.gz 12:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:19:26 INFO - Installing collected packages: blessings 12:19:26 INFO - Running setup.py install for blessings 12:19:26 INFO - Successfully installed blessings 12:19:26 INFO - Cleaning up... 12:19:26 INFO - Return code: 0 12:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:27 INFO - Reading from file tmpfile_stdout 12:19:27 INFO - Current package versions: 12:19:27 INFO - blessings == 1.6 12:19:27 INFO - blobuploader == 1.2.4 12:19:27 INFO - docopt == 0.6.1 12:19:27 INFO - functools32 == 3.2.3-2 12:19:27 INFO - jsonschema == 2.5.1 12:19:27 INFO - manifestparser == 1.1 12:19:27 INFO - mozInstall == 1.12 12:19:27 INFO - mozcrash == 0.17 12:19:27 INFO - mozdebug == 0.1 12:19:27 INFO - mozdevice == 0.48 12:19:27 INFO - mozfile == 1.2 12:19:27 INFO - mozhttpd == 0.7 12:19:27 INFO - mozinfo == 0.9 12:19:27 INFO - mozleak == 0.1 12:19:27 INFO - mozlog == 3.4 12:19:27 INFO - moznetwork == 0.27 12:19:27 INFO - mozprocess == 0.23 12:19:27 INFO - mozprofile == 0.28 12:19:27 INFO - mozrunner == 6.12 12:19:27 INFO - mozscreenshot == 0.1 12:19:27 INFO - mozsystemmonitor == 0.3 12:19:27 INFO - moztest == 0.7 12:19:27 INFO - mozversion == 1.4 12:19:27 INFO - psutil == 3.1.1 12:19:27 INFO - requests == 1.2.3 12:19:27 INFO - wsgiref == 0.1.2 12:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:27 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:27 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 12:19:27 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 12:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:27 INFO - 'HOME': '/Users/cltbld', 12:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:27 INFO - 'LOGNAME': 'cltbld', 12:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:27 INFO - 'MOZ_NO_REMOTE': '1', 12:19:27 INFO - 'NO_EM_RESTART': '1', 12:19:27 INFO - 'PAGER': '/bin/cat', 12:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:27 INFO - 'PWD': '/builds/slave/test', 12:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:27 INFO - 'SHELL': '/bin/bash', 12:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:27 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:27 INFO - 'USER': 'cltbld', 12:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:27 INFO - 'XPC_FLAGS': '0x0', 12:19:27 INFO - 'XPC_SERVICE_NAME': '0', 12:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 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)) 12:19:28 INFO - Cleaning up... 12:19:28 INFO - Return code: 0 12:19:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:19:28 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')]} 12:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:28 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')]} 12:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:28 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 0x10387b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0794c6b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, '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.wKS7nOOXSP/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.LdDlfYn34e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 12:19:28 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 12:19:28 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 12:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:28 INFO - 'HOME': '/Users/cltbld', 12:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:28 INFO - 'LOGNAME': 'cltbld', 12:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:28 INFO - 'MOZ_NO_REMOTE': '1', 12:19:28 INFO - 'NO_EM_RESTART': '1', 12:19:28 INFO - 'PAGER': '/bin/cat', 12:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:28 INFO - 'PWD': '/builds/slave/test', 12:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:28 INFO - 'SHELL': '/bin/bash', 12:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:19:28 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:28 INFO - 'USER': 'cltbld', 12:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:28 INFO - 'XPC_FLAGS': '0x0', 12:19:28 INFO - 'XPC_SERVICE_NAME': '0', 12:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:19:28 INFO - Cleaning up... 12:19:28 INFO - Return code: 0 12:19:28 INFO - Running post-action listener: _resource_record_post_action 12:19:28 INFO - Running post-action listener: _start_resource_monitoring 12:19:28 INFO - Starting resource monitoring. 12:19:28 INFO - [mozharness: 2016-10-23 19:19:28.830266Z] Finished create-virtualenv step (success) 12:19:28 INFO - [mozharness: 2016-10-23 19:19:28.831236Z] Running install step. 12:19:28 INFO - Running pre-action listener: _resource_record_pre_action 12:19:28 INFO - Running main action method: install 12:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:29 INFO - Reading from file tmpfile_stdout 12:19:29 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:19:29 INFO - Reading from file tmpfile_stdout 12:19:29 INFO - Output received: 12:19:29 INFO - Usage: mozinstall [options] installer 12:19:29 INFO - Options: 12:19:29 INFO - -h, --help show this help message and exit 12:19:29 INFO - -d DEST, --destination=DEST 12:19:29 INFO - Directory to install application into. [default: 12:19:29 INFO - "/builds/slave/test"] 12:19:29 INFO - --app=APP Application being installed. [default: firefox] 12:19:29 INFO - mkdir: /builds/slave/test/build/application 12:19:29 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'] 12:19:29 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 12:20:03 INFO - Reading from file tmpfile_stdout 12:20:03 INFO - Output received: 12:20:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:20:03 INFO - Running post-action listener: _resource_record_post_action 12:20:03 INFO - [mozharness: 2016-10-23 19:20:03.988377Z] Finished install step (success) 12:20:03 INFO - [mozharness: 2016-10-23 19:20:03.988531Z] Running run-tests step. 12:20:03 INFO - Running pre-action listener: _resource_record_pre_action 12:20:03 INFO - Running main action method: run_tests 12:20:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:20:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:20:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:20:04 INFO - 2016-10-23 12:20:04.003 screenresolution[1645:7660] starting screenresolution argv=screenresolution get 12:20:04 INFO - 2016-10-23 12:20:04.005 screenresolution[1645:7660] Display 0: 1600x1200x32@60 12:20:04 INFO - 2016-10-23 12:20:04.012 screenresolution[1646:7662] starting screenresolution argv=screenresolution list 12:20:04 INFO - Available Modes on Display 0 12:20:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:20:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:20:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:20:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:20:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:20:04 INFO - Intel Iris: 12:20:04 INFO - Chipset Model: Intel Iris 12:20:04 INFO - Type: GPU 12:20:04 INFO - Bus: Built-In 12:20:04 INFO - VRAM (Dynamic, Max): 1536 MB 12:20:04 INFO - Vendor: Intel (0x8086) 12:20:04 INFO - Device ID: 0x0a2e 12:20:04 INFO - Revision ID: 0x0009 12:20:04 INFO - Displays: 12:20:04 INFO - Display: 12:20:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:20:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:20:04 INFO - Display Serial Number: 1600x1200 60 12:20:04 INFO - Main Display: Yes 12:20:04 INFO - Mirror: Off 12:20:04 INFO - Online: Yes 12:20:04 INFO - Rotation: Supported 12:20:04 INFO - Return code: 0 12:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:20:04 INFO - Python 2.7.3 12:20:04 INFO - Return code: 0 12:20:04 INFO - grabbing minidump binary from tooltool 12:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:04 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 0x10368fd30>, '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 0x1034b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103687b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:20:04 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 12:20:04 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 12:20:04 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 12:20:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:20:04 INFO - Return code: 0 12:20:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:20:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:20:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:20:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:20:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:20:04 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/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--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 12:20:04 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/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0217 --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 12:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKS7nOOXSP/Render', 12:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:04 INFO - 'HOME': '/Users/cltbld', 12:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:04 INFO - 'LOGNAME': 'cltbld', 12:20:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:04 INFO - 'MOZ_NO_REMOTE': '1', 12:20:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:04 INFO - 'NO_EM_RESTART': '1', 12:20:04 INFO - 'PAGER': '/bin/cat', 12:20:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:04 INFO - 'PWD': '/builds/slave/test', 12:20:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:04 INFO - 'SHELL': '/bin/bash', 12:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LdDlfYn34e/Listeners', 12:20:04 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 12:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:04 INFO - 'USER': 'cltbld', 12:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:04 INFO - 'XPC_FLAGS': '0x0', 12:20:04 INFO - 'XPC_SERVICE_NAME': '0', 12:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:04 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/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--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 12:20:04 INFO - mozversion application_buildid: 20161023100823 12:20:04 INFO - mozversion application_changeset: 3d0ad7bb854d7b03609f6acecda4382b14f56e0b 12:20:04 INFO - mozversion application_display_name: Nightly 12:20:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:20:04 INFO - mozversion application_name: Firefox 12:20:04 INFO - mozversion application_remotingname: firefox 12:20:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:20:04 INFO - mozversion application_vendor: Mozilla 12:20:04 INFO - mozversion application_version: 52.0a1 12:20:04 INFO - mozversion platform_buildid: 20161023100823 12:20:04 INFO - mozversion platform_changeset: 3d0ad7bb854d7b03609f6acecda4382b14f56e0b 12:20:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:20:04 INFO - mozversion platform_version: 52.0a1 12:20:04 INFO - using testdate: 1477250404 12:20:04 INFO - actual date: 1477250404 12:20:04 INFO - starting webserver on 'localhost:49271' 12:20:04 INFO - SUITE-START | Running 2 tests 12:20:04 INFO - TEST-START | dromaeo_css 12:20:04 INFO - operating with platform_type : mac_ 12:20:04 INFO - Initialising browser for dromaeo_css test... 12:20:04 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmp8Akl35/profile http://localhost:49271/getInfo.html) 12:20:07 INFO - PROCESS | 1656 | 2016-10-23 12:20:07.465 plugin-container[1658:7813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 12:20:07 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:07 INFO - PROCESS | 1656 | 2016-10-23 12:20:07.468 plugin-container[1658:7813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b0b, name = 'com.apple.CFPasteboardClient' 12:20:07 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:07 INFO - PROCESS | 1656 | 2016-10-23 12:20:07.468 plugin-container[1658:7813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:07 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 12:20:07 INFO - PROCESS | 1656 | colorDepth:24 12:20:07 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 12:20:07 INFO - PROCESS | 1656 | __metrics 12:20:07 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 12:20:08 INFO - TEST-INFO | 1656: exit 0 12:20:08 INFO - Browser initialized. 12:20:08 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:08 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmp8Akl35/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:10 INFO - PROCESS | 1659 | 2016-10-23 12:20:10.510 plugin-container[1660:7931] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 12:20:10 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:10 INFO - PROCESS | 1659 | 2016-10-23 12:20:10.511 plugin-container[1660:7931] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 12:20:10 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:10 INFO - PROCESS | 1659 | 2016-10-23 12:20:10.511 plugin-container[1660:7931] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:20 INFO - PROCESS | 1659 | RSS: Main: 236929024 12:20:20 INFO - PROCESS | 1659 | 12:21:09 INFO - PROCESS | 1659 | 1477250469869 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] 12:23:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 12:23:24 INFO - PROCESS | 1659 | RSS: Main: 249638912 12:23:24 INFO - PROCESS | 1659 | 12:26:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 12:26:27 INFO - PROCESS | 1659 | RSS: Main: 263352320 12:26:27 INFO - PROCESS | 1659 | 12:29:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 12:29:29 INFO - PROCESS | 1659 | RSS: Main: 268267520 12:29:29 INFO - PROCESS | 1659 | 12:32:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 12:32:32 INFO - PROCESS | 1659 | RSS: Main: 268083200 12:32:32 INFO - PROCESS | 1659 | 12:35:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 12:35:35 INFO - PROCESS | 1659 | RSS: Main: 273539072 12:35:35 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 12:38:38 INFO - PROCESS | 1659 | RSS: Main: 276111360 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | __start_tp_report 12:38:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 12:38:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 12:38:38 INFO - PROCESS | 1659 | |i|pagename|runs| 12:38:38 INFO - PROCESS | 1659 | |0;dojo.html;2555.9616605750916;3354.815485148316;3370.8651653933844;3378.543896573963;3431.536742539758;5738.598298119128;5790.536757059435;5804.941950580493;5824.3010838699365;5829.825105246839;5643.492085712295;5811.186433899247;5815.069588865782;5853.239078919734;5930.940690593104;5188.221766734991;5274.208868669692;5290;5302.416734159239;5314.4951229633125;5266.868328291805;5348.331458567679;5352.57179425647;5355.169948657965;5450.809221677242;5176.6893986360565;5296.443873393316;5301.363836339664;5333.679979201241;5342.599305052117;4270.338097594878;4403.735775853459;5202.739863006872;5263.105272103751;5367.5169234769055;5149.253358263069;5162.251473536354;5229.869253268661;5249.973750131263;5280.234366016906;4283.464566929134;5693.430656934315;5768.394318596529;5783.132530120473;5785.4214578541805;6104.7926496150985;6123.632582045091;6141.946315431794;6224.657643829588;6228.470580000705;6258;6260.373962603703;6272.5922815016875;6290.245170579514;6295.401936815995;5948.40515948406;5979.372165922559;5980.282366116094;5990.822358445061;6017.849553761103;5556.972215138899;5938.406159384027;6145.187169784924;6173.59871608344;6309;6175;6197.070439434031;6207.782727604603;6209.379062093754;6226.25284965807;6216.875662486725;6278.372162783685;6293.779717709858;6297.748090076438;6297.874042519215;6026.668533230627;6134.895838532271;6148.44663980244;6201.162843016135;6256.405641464054;6145.078238264206;6169.074638804125;6193.4425901669065;6215.875682486325;6232.470240029648;4985.1276026695905;4988.152014157529;4989.451160372356;4995.350604421402;5030.3963524377305;4451.0430257494145;4477.8208871644765;4488.685791994594;4494.617957473586;4495.393121928563;5745.368009518949;5778.739956701958;5872;5885.588008839341;5938.2280303560265;6204.689765511706;6229.626222426669;6251.874962500725;6285.151504546919;6287.622742635456;5901.3508514063415;5914.378990206053;5936.970315148483;5940.910886336708;5984.850378740479;5350.38470575881;5357.892842143213;5394.460553944574;5468.863278418072;5470.753816078286;6301.558890877594;6327.841803954938;6328.177336946167;6413.358664133736;6430.678466076771;6253.1245625613365;6256.937430625636;6258.374162583706;6266.905996409966;6268.247810262797;6166.876662466636;6381.553291269566;6391.7123729433215;6401.231852177582;6413.685194535015;6132.01899668756;6143.419763699347;6143.631382117087;6208.192934918432;6217.813465596039;6147.354527774519;6260.311365749855;6267.529935254783;6275.968620157053;6280.183576135256;4720.669553131248;5723.912456633227;6197.752089916353;6208.9689551551955;6213.378662133931;4153.480814898137;5896.115582662635;6108.888502348341;6114.724837382418;6127.877442451215;4996.675216110941;5898.439648233411;6086.2087928569;6088.939110609014;6120.112583675431;6040.305364883095;6072.453479186717;6095.634261944298;6115.082737589397;6117.218487018656;4731.929021064756;6003.909941350796;6018.8796224074395;6021.765538064774;6026.819195424145 12:38:38 INFO - PROCESS | 1659 | |1;ext.html;525.8133362656256;531.2562412622328;532.2787622771145;535.5367607019928;540.4595404595404;38374.99999999996;39103.60896391039;40439.79780101099;40458.78623641288;42737.14525709484;41904.79047604765;42210;42563.36154957678;42755.786221068855;43002;8720.040795512488;8968.417052891542;9459.668911588113;9663.806723865519;9695.563699633534;9518.904810951906;9542.952285238563;9566.760830979247;9645.951770241174;9657.082577155194;8766;8914.86627700585;9658.03419658032;9671.226301895831;9675.612975481008;9627.711168664917;9636.421814691106;9640.807183856354;9664.420134791935;9668.854967175497;9346.299027572959;9391.483468409235;9422.104900034486;9430.10414010668;9473.715788526355;39503.60496395028;40002.999925001815;40163.99590010244;40181;40302.798486007676;3371.6459771724108;3497.615262321143;3547.94678079829;3847.5190601174854;4222.514410842762;29980.100596982127;30685.539716904263;31203.531947020587;31247.531287030484;31305.84347078273;3123.6251649801943;3263.0863358259726;3283.83580820958;3322.0033989803205;3323.850426730803;490002;490316;491458;496019;496327;54199;58334;58580.70709646425;58806.7059664699;58983.70508147518;61530;61742;61866.0720089199;61973.380266197666;62022.68988655028;15659.765103523456;15912.443064492687;15924.283407246701;15932.761008584881;15955.962862413906;12274.693132671577;12287.1399002069;12350.135490515577;12358.073144514197;12402.875971240172;15501.612459688598;15986.36054557828;16042.278097485641;16058.27737751804;16066.75899861503;3184.792988455743;6743.425984228367;6988.615626140611;7004.894926576106;7120.216776154619;746.9579935989265;898.5866501409295;910.7313342563959;1061.3260579532052;1152.7291086594644;23007.884960575426;28957.710422895503;29779.553306700418;30079.248018799706;30161.698383016326;1013.2248829645328;1022.483645758887;1066.4987455895716;1135.3017893995134;1139.4530625299737;13669.931650341883;13949.181164049349;15367.923160384127;15481.148536830582;15759;654113;662770;665843;666985;668755;57277;57329.14006289909;57999.71000145056;58003.70998144982;58145.12782308269;1329.222404893129;1367.9452821887014;1372.876441120304;1385.861413858606;1394.9442022318995;26652.60021099646;27688.030918917742;28809.135725928256;30031;30123.397532049676;48162.036759263916;53352.733236333566;55385;55518.16722749081;58997.41002589919;593106;595666;602198;610781;611499;41864.581354187285;42496.28083908234;42843.785781070896;42970.78514607532;43294.567054329054;46754.53245467502;47227.52772472231;47959.5204047969;48252.758736206095;48528.7573562144;39150;39380.80309598434;39447.605523944396;40895.591044089175;40958.180836383704;33505.994820155436;39170;39367.21265574615;40297.99255018648;40458;38045.61954380532;38405.03987400337;38543.80728096342;38782;38807.6119238804;26644;33476.33047339088;34846.25768711605;34975.12562185965;35153.64846351504;21857.890710546344;24143.396415089763;25408;25945.96216151333;26469 12:38:38 INFO - PROCESS | 1659 | |2;jquery.html;315.9383920135574;320.9277912469693;343.390481894637;374.836945928521;380.305941656477;18957.33649322274;19679.311224107158;20655.896720516415;20865.582688346225;21000.159993600275;16589.585260368498;20415;20656.277030303903;20923.849188294636;20986.475338116554;4876.829310974116;4945.752712364385;5347.866303342409;5697.772089116441;5752.856178595548;4816.54242846929;5507;5831.766729330843;5906.61606995546;5919.2009078774245;5277.3139491866;5798.652080875161;5846.50304724099;5868.501177399926;5869.207656966319;5521.868017056494;5755.568332375067;5761.510271626875;5789.623674461168;5797.507211886995;5019.899602007939;5056.115179843513;5106.719130447824;5114.181730923035;5124.692518448867;22778;23974.640380394307;24122.87938560296;24297.392565185834;24390;2131.126238242329;2421.07359824719;2602.557565213918;2665;2860.756835668971;9853.211743060538;9887.456189909552;10158.542865571068;10167.89832101677;13700.3834827433;1686.207482483231;1778.3070358241591;1832.5235438785878;1879.1825555883097;1912.1968773115186;119842;125318;127830.08254876184;128128.99999999907;129006;9366.641836933606;10309.587616495406;11089.722756931142;11405.600803971822;11470;14397.99214055006;14430.206338651475;14449.133052016703;14453.927730361282;14474.131552106906;8097.635606397726;8317.209865062809;8449.01448159682;8471.237588617054;8477.95761021191;6795.320467953264;8114.513129212266;8150.592470376576;8257.917420825836;8505.74482765518;3375.7974521528786;4526.456825181;4544;4553.886152846206;4625.306204069349;1896.6586014517243;1933.5552822850823;1935.9903200483907;1953.7557805274341;1958.6180694764669;720;751.1136858507015;917.4632839788665;947.4457442396275;978.8433850583872;20719;21171.576568468543;21371.46571335729;21455.892720536296;21618.919054047234;926.7358802741321;948.2650945517141;948.7153853843819;953.6900507335005;961.1974001708512;10513.89486105129;11219.551217951353;11262.08544547429;11270.887291127148;11313.83029254562;581609;582803;588482;600763;603339;130268;131523;134071.61498694998;134820;136052.31973840066;841.869510225916;1007.7581380468635;1027.3989716016065;1029.9382037077798;1030.0832259289389;18308.08459576989;18785.436436906915;18913.621727565645;18914.43256702301;18980.43058708241;53394.73302633617;53482.93034139373;53504;53581;55075;74668;76819.23180768121;78284.60857695903;79620.20379796128;82901.58549207215;7263.782086537412;7658.846823063619;7761.301482866569;7844.058712954482;7980.8802867955865;12144.42880647048;12542;12783.424745886272;12865.421056052688;12924.224546527239;10770.353778773298;10787.946060269649;10942.124630029419;11099.112071034458;11323.037541809039;10580.523876425425;10830.945845270724;11248.550057997589;11300.208985371273;11331;11018;11061.557537698402;11165.944170279097;11550.018248448976;11554.826677600011;6183.536234782499;6301.338359472189;6542.771003015062;7103.183133939663;7916.445848790529;11085.390303533382;11128.833067503832;11347.943260283646;11369.829452558053;11389.772204556028 12:38:38 INFO - PROCESS | 1659 | |3;mootools.html;1380.2063813307348;1599.6240883392402;1707.4877536738984;1788.5796837743137;1905.4569447707388;7198;7784.96107519463;8034.116247212802;8776.736697899065;8802.339824513156;8275.255227029582;8574.614142363593;8589.01226358969;8640.91359086409;8645;1860.4697661166535;2286.142696488817;2295.4490922178725;2297.058206135485;2302.804261637763;2241.932742017742;2275.306031660343;2298.5632729781373;2304;2309.203324852923;2292.587334279829;2306.08909480755;2306.6885970394032;2311.537692461506;2312.976870231293;2247.0000849621924;2252.3243027091808;2256.0750092462094;2256.2103263857684;2258.0516183203094;2233.1179184222306;2241.1371621925514;2249.044156233594;2255.4699645583273;2257.288953979491;8664.913350866475;8676.8698469523;8718.694845680371;8725.694600688945;8741.825163496695;5106.157484015133;5164.173732202867;5241.606879484016;5261.789528418859;5273.367195936512;3278.1476816027766;3401.098708842159;3506.9824650876835;3516.753827232095;3523.859045638171;1021.8160731068405;1036.0675392147052;1048.0201012053753;1052.4001319248032;1052.752603138262;10651.946740266327;10981.231313808037;10994.725131871688;11003.284786488932;11026.44867756617;4267.317229243306;4341;4420.823167073346;4476.462824461086;4500.3249512573375;8776.122387761172;8868.068852770495;8869.11308869108;8880.467171969702;8893.466392016371;5038.244263360452;5069.239614057921;5094.2103973884105;5097.439281679012;5105.106606343875;3953.5453422856162;4307.353896915488;4433.733975961452;4790.281457781291;4805.471398146201;3629.1471504196493;3647.507586475845;3701.648343407372;3725.8137093145233;3733.906652333659;1121.6018313498591;1128.9604863829727;1137.0789660375124;1138.1009002887627;1144.8969592736528;683.3371629519359;730.936487410816;893.7006102955582;916.2852974679761;938.6057855700623;5703.0019746544185;5777.653340799481;5830.271216097988;5871.588988770745;5876.324222714443;854.5342788180457;927.888653361601;958.2046901072093;961.8938221045713;992.1566668332005;3585.1574879903724;3825.885223443301;3871.922561548753;3945.8027098644952;3961.801909904493;10222.080012798884;10531.05220530156;10536.10443112344;10543.94728026355;10559.577616895238;9453.716388508356;9900.752481188028;10381;10492.422916739653;10532.999365060306;6748.932510674928;7881.802954926058;8025.518468891885;8037.115917249098;8043.235892590312;4616.49218585962;5748.48263656273;5789.56578256624;5881.411858814085;6161.229846269216;9365.531723413938;9379.108984646447;9463.574139163617;9479;9502.667406640734;9195.448273103635;9522.952385238306;9525.761855953378;9668;9692.85460718104;6953.687084081127;7135.500514963903;7234.819129521804;7329.303716146957;7493.40052795786;7990.760277191694;8070.273675369245;8076.798080047811;8078.959605201936;8087.1104178539135;7096.325849044332;7185.209626940928;7228.530145540523;7244.529105608119;7320.743773968099;7082;7091.432685385053;7101.964490177516;7138.643067846483;7144.857102858018;6487.967560162169;6645;6722.563033402813;6754.628495432602;6802.251752307341;4793.592544633745;4831.9275210871165;4846.830360937351;4860.975695121501;4918.163912134874;4649.000464900063;4832.540908613676;4850.684705494132;4969.180085285887;5019.498050194951 12:38:38 INFO - PROCESS | 1659 | |4;prototype.html;290.88073889705214;296.26082923106844;304.82320254252534;306.93861227754456;309.11592844464826;5259.631825772188;5374.596905232113;5439.891202175959;5498.285222921019;5535.474129957647;4472.686911916167;5158.277841102239;5187.922181167285;5341.732913354336;5388.335931566533;4064.4106604542326;4081.755004771214;4084.794211126003;4106.219818234541;4115.9176816463805;3807.5430948286244;4055.148418832034;4121.248469400508;4133.9173216535655;4134.855280065199;3677.852885884575;3934.1148241645687;4061.4110953911663;4071.7760523171214;4077.9601201693677;3311.9834400827963;3693.298273328059;4071.42999980003;4077.67378609712;4085.7946905662902;2953.7489313408387;3547.6807087362263;3549.5208146900354;3557.2529768748595;3562.8931132066077;4229.6404805591565;4542.227821270359;5016.774245158977;5282.498162674539;5324.680519168824;4606;4611.930821037688;4618.930716039262;4633.953660463386;4634.72191668501;5020.59835213182;5631.577631677641;5663.867226554706;5692.922083439515;5694.316681998145;3322.833858307075;3676.290769345928;3843.250566139604;3851.2105018471148;3854.7879866607327;5982.341942386334;5986.73059712314;6014.65428482892;6043.969780151115;6079.969600152015;3882.1653344530496;3904.6485816276104;3909.7458665186673;3930.665893399036;3930.901727456779;3853.6339047790416;3911.530616326059;3969.583193764671;3981.024648961023;3984.462097616785;3577.99816051506;3807.8476860925807;4340.60934515895;4538.364628951949;4637.582617564435;5223.764930578133;5692.943070569324;5747.48272655463;5776.277965254343;5794.913076303858;1050.0864248104199;1309.7380523895258;1311.2460335307237;1325.9138156020022;1340.9463621455031;776.825214326772;855.3969451536652;905.917428672743;907.2333877873186;909.1681111782644;4575;5269.314989051475;5303.208206132634;5375.946240537544;5378.489043540857;5792.768289268466;5820.214271073435;5825.475707186289;5833.445822646841;5890.911636325459;4662.626989840873;5018.591076283405;5075.97462012695;5276.5251127398715;5383.650062745987;2060.1553363121047;2218.001899145359;2546.1979476464853;2553.795696344288;2563.4621724492617;4380.255356589388;4885.682430641997;5032.974835125801;5101.642884998088;5122.641415100908;4268.509121451073;4843.588294994965;5032.445636698793;5654.547636189095;6125.387461253839;6189.122058242939;6196.504279657706;6205.627662340184;6232.974283357927;6245.78207249591;5390.706230504729;5589.469000444951;5990.580659353804;6012.669303188367;6044.184035155197;4683.929741053818;5162.432132465351;5307.65500242483;5616.770357044634;5778.27771528559;6197.628142311476;6204.844878878064;6233.875322493615;6245.7189426476825;6287.559870809182;3828.980855095707;3892.630200130983;3925.134651704215;3939.409088636613;3949.7235193537326;5524.558035357081;5603.422425684231;5813.24427824397;5975.671338076274;5993.940060599338;5198.324217851655;5518.255035570147;5642.830715078554;5671.177679236405;5762.349319313163;4720.173969555382;5732.398098199796;5756.251687280626;5763.7982670606325;5774.624649397776;4919.532644398776;5201.71390573507;5250.789968401221;5376.086065368819;5660.632058916157;5762.250907382014;5773.3072031356505;5784.566157538117;5795.8551036224435;5805.332386775575;5482.862928426821;5607.607467477237;5665.376808551138;5676.631018983918;5692.829215123553 12:38:38 INFO - PROCESS | 1659 | |5;yui.html;650.3204151661513;692.4425837201057;693.4943858793798;776.3207193705508;834.5076404921098;12916.225026498416;12956.48174073037;15012.249387530634;16256.268467918942;16276.430324938614;14600.92699536501;14858.33137508812;14969.326380312885;15067.77398339023;15069.910668312998;894.9239314658262;904.5612877754284;905.6739573753443;908.2325435007408;908.6774195160722;700.6216643012767;721.8051126195924;872.650939624149;900.6082354175954;903.9819203615924;932.6316105138466;958.832204364237;968;968.138356862393;970.1462712812715;942.0767647705219;960.4237457525498;962.311946957925;963.0562049191761;967.472727363585;712.2841544247998;714.8534550417204;715.2668514772348;715.5420530860254;717.6124892558065;15663.21683915811;15744.527664170093;15914.442994495254;15918.840811591852;15920.124393158372;14580.489682861155;14599.635009124751;14810.407583696639;14851.925740371229;14919.254037298091;11824.645260642108;11843.763124737543;11997.700057498632;12063.577774777928;12219.877801221963;3700.666939975415;3727.7390582659223;3877.3602355611292;3916.3929590913453;3922.2547715934165;113276.43361783221;114755.42622286835;115485.42257288744;116733.832661674;117091;10956.56173753041;10997.725056873642;11181.097646189795;11294.983451489405;11386.829197562043;23375;23395;23401.478903871193;23401.88299058528;23437.88281058618;7847.039449135072;8008.719694810654;8102.432829701865;8160.224778646019;8241.711540096068;4311.137772445523;4356.738595684269;4373.995588073817;4436.467623885154;4501.774911254424;3457.7752446091426;3589.174489867293;3594.748367614242;3595.658412450813;3603.94594081089;553.97784088636;628.1896353703762;635.0696230023038;660.1352228580574;664.0039940089865;568.9731224630436;585.3911931591183;646.9971544106616;671.5342969254497;674.2650510943096;8096.311813495919;8530.360222983301;9047.276217902552;9257.213136883305;9332.113449222314;644.6196743921109;662.7150325360045;675.2774531251514;679.4666187043148;680.7549282258383;3804.4863943367845;4078.3066878630702;4211.852585159566;4730.668853180313;4847.345608342828;94383.0561694388;125129.37435312766;147175;155213;163560;111976;137429.5028198881;138494.3075284617;139503;142494;102382.48808755909;103506.48246758869;108933;124253;124972;9708.951455242679;9802.705918822447;9874.151986062554;9975.251856110966;10235.692929212135;64365.35634643594;69335.65332173307;69740.65129674319;70480.64759676169;71282.28717712757;97260;106676.46661766642;107307;107518.46240768746;108099.6760129586;14128.858711413166;14229.573112806633;14298;14502;14616;14750.852491474949;14911.925440372728;15048.322825472662;15609;15630.140342281284;12562.685932851751;13028.739425211254;14046.438142474186;14107.224102674041;14625.122492650049;13533.961916431306;13581.592552223448;13594.252316122709;13609.727805443637;13742;13512.189268643913;13627.773500385012;13840;13868.514601988882;14290.785638215228;11062.280951738112;11107.222494425636;11337;11448.599299024494;11987.34069626179;9061.54692265371;10445.373277603368;10826.729331766768;11437.42812859337;11703.23928944616 12:38:38 INFO - PROCESS | 1659 | __end_tp_report 12:38:38 INFO - PROCESS | 1659 | __start_cc_report 12:38:38 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,362 12:38:38 INFO - PROCESS | 1659 | __end_cc_report 12:38:38 INFO - PROCESS | 1659 | __startTimestamp1477251518708__endTimestamp 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | ------- Summary: start ------- 12:38:38 INFO - PROCESS | 1659 | Number of tests: 6 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5729.80 Median:5990.82 stddev:694.34 (11.6%) stddev-sans-first:650.30 12:38:38 INFO - PROCESS | 1659 | Values: 2556.0 3354.8 3370.9 3378.5 3431.5 5738.6 5790.5 5804.9 5824.3 5829.8 5643.5 5811.2 5815.1 5853.2 5930.9 5188.2 5274.2 5290.0 5302.4 5314.5 5266.9 5348.3 5352.6 5355.2 5450.8 5176.7 5296.4 5301.4 5333.7 5342.6 4270.3 4403.7 5202.7 5263.1 5367.5 5149.3 5162.3 5229.9 5250.0 5280.2 4283.5 5693.4 5768.4 5783.1 5785.4 6104.8 6123.6 6141.9 6224.7 6228.5 6258.0 6260.4 6272.6 6290.2 6295.4 5948.4 5979.4 5980.3 5990.8 6017.8 5557.0 5938.4 6145.2 6173.6 6309.0 6175.0 6197.1 6207.8 6209.4 6226.3 6216.9 6278.4 6293.8 6297.7 6297.9 6026.7 6134.9 6148.4 6201.2 6256.4 6145.1 6169.1 6193.4 6215.9 6232.5 4985.1 4988.2 4989.5 4995.4 5030.4 4451.0 4477.8 4488.7 4494.6 4495.4 5745.4 5778.7 5872.0 5885.6 5938.2 6204.7 6229.6 6251.9 6285.2 6287.6 5901.4 5914.4 5937.0 5940.9 5984.9 5350.4 5357.9 5394.5 5468.9 5470.8 6301.6 6327.8 6328.2 6413.4 6430.7 6253.1 6256.9 6258.4 6266.9 6268.2 6166.9 6381.6 6391.7 6401.2 6413.7 6132.0 6143.4 6143.6 6208.2 6217.8 6147.4 6260.3 6267.5 6276.0 6280.2 4720.7 5723.9 6197.8 6209.0 6213.4 4153.5 5896.1 6108.9 6114.7 6127.9 4996.7 5898.4 6086.2 6088.9 6120.1 6040.3 6072.5 6095.6 6115.1 6117.2 4731.9 6003.9 6018.9 6021.8 6026.8 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:71948.16 Median:41411.38 stddev:157951.00 (381.4%) stddev-sans-first:158303.07 12:38:38 INFO - PROCESS | 1659 | Values: 525.8 531.3 532.3 535.5 540.5 38375.0 39103.6 40439.8 40458.8 42737.1 41904.8 42210.0 42563.4 42755.8 43002.0 8720.0 8968.4 9459.7 9663.8 9695.6 9518.9 9543.0 9566.8 9646.0 9657.1 8766.0 8914.9 9658.0 9671.2 9675.6 9627.7 9636.4 9640.8 9664.4 9668.9 9346.3 9391.5 9422.1 9430.1 9473.7 39503.6 40003.0 40164.0 40181.0 40302.8 3371.6 3497.6 3547.9 3847.5 4222.5 29980.1 30685.5 31203.5 31247.5 31305.8 3123.6 3263.1 3283.8 3322.0 3323.9 490002.0 490316.0 491458.0 496019.0 496327.0 54199.0 58334.0 58580.7 58806.7 58983.7 61530.0 61742.0 61866.1 61973.4 62022.7 15659.8 15912.4 15924.3 15932.8 15956.0 12274.7 12287.1 12350.1 12358.1 12402.9 15501.6 15986.4 16042.3 16058.3 16066.8 3184.8 6743.4 6988.6 7004.9 7120.2 747.0 898.6 910.7 1061.3 1152.7 23007.9 28957.7 29779.6 30079.2 30161.7 1013.2 1022.5 1066.5 1135.3 1139.5 13669.9 13949.2 15367.9 15481.1 15759.0 654113.0 662770.0 665843.0 666985.0 668755.0 57277.0 57329.1 57999.7 58003.7 58145.1 1329.2 1367.9 1372.9 1385.9 1394.9 26652.6 27688.0 28809.1 30031.0 30123.4 48162.0 53352.7 55385.0 55518.2 58997.4 593106.0 595666.0 602198.0 610781.0 611499.0 41864.6 42496.3 42843.8 42970.8 43294.6 46754.5 47227.5 47959.5 48252.8 48528.8 39150.0 39380.8 39447.6 40895.6 40958.2 33506.0 39170.0 39367.2 40298.0 40458.0 38045.6 38405.0 38543.8 38782.0 38807.6 26644.0 33476.3 34846.3 34975.1 35153.6 21857.9 24143.4 25408.0 25946.0 26469.0 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35096.45 Median:13359.23 stddev:99167.77 (742.3%) stddev-sans-first:99411.57 12:38:38 INFO - PROCESS | 1659 | Values: 315.9 320.9 343.4 374.8 380.3 18957.3 19679.3 20655.9 20865.6 21000.2 16589.6 20415.0 20656.3 20923.8 20986.5 4876.8 4945.8 5347.9 5697.8 5752.9 4816.5 5507.0 5831.8 5906.6 5919.2 5277.3 5798.7 5846.5 5868.5 5869.2 5521.9 5755.6 5761.5 5789.6 5797.5 5019.9 5056.1 5106.7 5114.2 5124.7 22778.0 23974.6 24122.9 24297.4 24390.0 2131.1 2421.1 2602.6 2665.0 2860.8 9853.2 9887.5 10158.5 10167.9 13700.4 1686.2 1778.3 1832.5 1879.2 1912.2 119842.0 125318.0 127830.1 128129.0 129006.0 9366.6 10309.6 11089.7 11405.6 11470.0 14398.0 14430.2 14449.1 14453.9 14474.1 8097.6 8317.2 8449.0 8471.2 8478.0 6795.3 8114.5 8150.6 8257.9 8505.7 3375.8 4526.5 4544.0 4553.9 4625.3 1896.7 1933.6 1936.0 1953.8 1958.6 720.0 751.1 917.5 947.4 978.8 20719.0 21171.6 21371.5 21455.9 21618.9 926.7 948.3 948.7 953.7 961.2 10513.9 11219.6 11262.1 11270.9 11313.8 581609.0 582803.0 588482.0 600763.0 603339.0 130268.0 131523.0 134071.6 134820.0 136052.3 841.9 1007.8 1027.4 1029.9 1030.1 18308.1 18785.4 18913.6 18914.4 18980.4 53394.7 53482.9 53504.0 53581.0 55075.0 74668.0 76819.2 78284.6 79620.2 82901.6 7263.8 7658.8 7761.3 7844.1 7980.9 12144.4 12542.0 12783.4 12865.4 12924.2 10770.4 10787.9 10942.1 11099.1 11323.0 10580.5 10830.9 11248.6 11300.2 11331.0 11018.0 11061.6 11165.9 11550.0 11554.8 6183.5 6301.3 6542.8 7103.2 7916.4 11085.4 11128.8 11347.9 11369.8 11389.8 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5472.42 Median:5081.73 stddev:3039.11 (59.8%) stddev-sans-first:3032.07 12:38:38 INFO - PROCESS | 1659 | Values: 1380.2 1599.6 1707.5 1788.6 1905.5 7198.0 7785.0 8034.1 8776.7 8802.3 8275.3 8574.6 8589.0 8640.9 8645.0 1860.5 2286.1 2295.4 2297.1 2302.8 2241.9 2275.3 2298.6 2304.0 2309.2 2292.6 2306.1 2306.7 2311.5 2313.0 2247.0 2252.3 2256.1 2256.2 2258.1 2233.1 2241.1 2249.0 2255.5 2257.3 8664.9 8676.9 8718.7 8725.7 8741.8 5106.2 5164.2 5241.6 5261.8 5273.4 3278.1 3401.1 3507.0 3516.8 3523.9 1021.8 1036.1 1048.0 1052.4 1052.8 10651.9 10981.2 10994.7 11003.3 11026.4 4267.3 4341.0 4420.8 4476.5 4500.3 8776.1 8868.1 8869.1 8880.5 8893.5 5038.2 5069.2 5094.2 5097.4 5105.1 3953.5 4307.4 4433.7 4790.3 4805.5 3629.1 3647.5 3701.6 3725.8 3733.9 1121.6 1129.0 1137.1 1138.1 1144.9 683.3 730.9 893.7 916.3 938.6 5703.0 5777.7 5830.3 5871.6 5876.3 854.5 927.9 958.2 961.9 992.2 3585.2 3825.9 3871.9 3945.8 3961.8 10222.1 10531.1 10536.1 10543.9 10559.6 9453.7 9900.8 10381.0 10492.4 10533.0 6748.9 7881.8 8025.5 8037.1 8043.2 4616.5 5748.5 5789.6 5881.4 6161.2 9365.5 9379.1 9463.6 9479.0 9502.7 9195.4 9523.0 9525.8 9668.0 9692.9 6953.7 7135.5 7234.8 7329.3 7493.4 7990.8 8070.3 8076.8 8079.0 8087.1 7096.3 7185.2 7228.5 7244.5 7320.7 7082.0 7091.4 7102.0 7138.6 7144.9 6488.0 6645.0 6722.6 6754.6 6802.3 4793.6 4831.9 4846.8 4861.0 4918.2 4649.0 4832.5 4850.7 4969.2 5019.5 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4567.69 Median:5160.35 stddev:1462.39 (28.3%) stddev-sans-first:1430.82 12:38:38 INFO - PROCESS | 1659 | Values: 290.9 296.3 304.8 306.9 309.1 5259.6 5374.6 5439.9 5498.3 5535.5 4472.7 5158.3 5187.9 5341.7 5388.3 4064.4 4081.8 4084.8 4106.2 4115.9 3807.5 4055.1 4121.2 4133.9 4134.9 3677.9 3934.1 4061.4 4071.8 4078.0 3312.0 3693.3 4071.4 4077.7 4085.8 2953.7 3547.7 3549.5 3557.3 3562.9 4229.6 4542.2 5016.8 5282.5 5324.7 4606.0 4611.9 4618.9 4634.0 4634.7 5020.6 5631.6 5663.9 5692.9 5694.3 3322.8 3676.3 3843.3 3851.2 3854.8 5982.3 5986.7 6014.7 6044.0 6080.0 3882.2 3904.6 3909.7 3930.7 3930.9 3853.6 3911.5 3969.6 3981.0 3984.5 3578.0 3807.8 4340.6 4538.4 4637.6 5223.8 5692.9 5747.5 5776.3 5794.9 1050.1 1309.7 1311.2 1325.9 1340.9 776.8 855.4 905.9 907.2 909.2 4575.0 5269.3 5303.2 5375.9 5378.5 5792.8 5820.2 5825.5 5833.4 5890.9 4662.6 5018.6 5076.0 5276.5 5383.7 2060.2 2218.0 2546.2 2553.8 2563.5 4380.3 4885.7 5033.0 5101.6 5122.6 4268.5 4843.6 5032.4 5654.5 6125.4 6189.1 6196.5 6205.6 6233.0 6245.8 5390.7 5589.5 5990.6 6012.7 6044.2 4683.9 5162.4 5307.7 5616.8 5778.3 6197.6 6204.8 6233.9 6245.7 6287.6 3829.0 3892.6 3925.1 3939.4 3949.7 5524.6 5603.4 5813.2 5975.7 5993.9 5198.3 5518.3 5642.8 5671.2 5762.3 4720.2 5732.4 5756.3 5763.8 5774.6 4919.5 5201.7 5250.8 5376.1 5660.6 5762.3 5773.3 5784.6 5795.9 5805.3 5482.9 5607.6 5665.4 5676.6 5692.8 12:38:38 INFO - PROCESS | 1659 | 12:38:38 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25645.21 Median:23401.68 stddev:40646.78 (173.7%) stddev-sans-first:40717.48 12:38:38 INFO - PROCESS | 1659 | Values: 650.3 692.4 693.5 776.3 834.5 12916.2 12956.5 15012.2 16256.3 16276.4 14600.9 14858.3 14969.3 15067.8 15069.9 894.9 904.6 905.7 908.2 908.7 700.6 721.8 872.7 900.6 904.0 932.6 958.8 968.0 968.1 970.1 942.1 960.4 962.3 963.1 967.5 712.3 714.9 715.3 715.5 717.6 15663.2 15744.5 15914.4 15918.8 15920.1 14580.5 14599.6 14810.4 14851.9 14919.3 11824.6 11843.8 11997.7 12063.6 12219.9 3700.7 3727.7 3877.4 3916.4 3922.3 113276.4 114755.4 115485.4 116733.8 117091.0 10956.6 10997.7 11181.1 11295.0 11386.8 23375.0 23395.0 23401.5 23401.9 23437.9 7847.0 8008.7 8102.4 8160.2 8241.7 4311.1 4356.7 4374.0 4436.5 4501.8 3457.8 3589.2 3594.7 3595.7 3603.9 554.0 628.2 635.1 660.1 664.0 569.0 585.4 647.0 671.5 674.3 8096.3 8530.4 9047.3 9257.2 9332.1 644.6 662.7 675.3 679.5 680.8 3804.5 4078.3 4211.9 4730.7 4847.3 94383.1 125129.4 147175.0 155213.0 163560.0 111976.0 137429.5 138494.3 139503.0 142494.0 102382.5 103506.5 108933.0 124253.0 124972.0 9709.0 9802.7 9874.2 9975.3 10235.7 64365.4 69335.7 69740.7 70480.6 71282.3 97260.0 106676.5 107307.0 107518.5 108099.7 14128.9 14229.6 14298.0 14502.0 14616.0 14750.9 14911.9 15048.3 15609.0 15630.1 12562.7 13028.7 14046.4 14107.2 14625.1 13534.0 13581.6 13594.3 13609.7 13742.0 13512.2 13627.8 13840.0 13868.5 14290.8 11062.3 11107.2 11337.0 11448.6 11987.3 9061.5 10445.4 10826.7 11437.4 11703.2 12:38:38 INFO - PROCESS | 1659 | -------- Summary: end -------- 12:38:38 INFO - PROCESS | 1659 | 12:38:39 INFO - TEST-INFO | 1659: exit 0 12:38:39 INFO - TEST-OK | dromaeo_css | took 1114763ms 12:38:39 INFO - TEST-START | kraken 12:38:39 INFO - operating with platform_type : mac_ 12:38:39 INFO - Initialising browser for kraken test... 12:38:39 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpzPiKiA/profile http://localhost:49271/getInfo.html) 12:38:41 INFO - PROCESS | 1668 | 2016-10-23 12:38:41.819 plugin-container[1669:9991] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 12:38:41 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:41 INFO - PROCESS | 1668 | 2016-10-23 12:38:41.822 plugin-container[1669:9991] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1b, name = 'com.apple.CFPasteboardClient' 12:38:41 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:41 INFO - PROCESS | 1668 | 2016-10-23 12:38:41.822 plugin-container[1669:9991] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:41 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:38:41 INFO - PROCESS | 1668 | colorDepth:24 12:38:41 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:38:41 INFO - PROCESS | 1668 | __metrics 12:38:41 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 12:38:42 INFO - TEST-INFO | 1668: exit 0 12:38:42 INFO - Browser initialized. 12:38:42 INFO - Running cycle 1/1 for kraken test... 12:38:42 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpzPiKiA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:45 INFO - PROCESS | 1670 | 2016-10-23 12:38:45.232 plugin-container[1671:10110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 12:38:45 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:45 INFO - PROCESS | 1670 | 2016-10-23 12:38:45.232 plugin-container[1671:10110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8827, name = 'com.apple.CFPasteboardClient' 12:38:45 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:45 INFO - PROCESS | 1670 | 2016-10-23 12:38:45.232 plugin-container[1671:10110] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:54 INFO - PROCESS | 1670 | RSS: Main: 236711936 12:38:54 INFO - PROCESS | 1670 | 12:38:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 12:38:58 INFO - PROCESS | 1670 | RSS: Main: 249036800 12:38:58 INFO - PROCESS | 1670 | 12:39:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 12:39:01 INFO - PROCESS | 1670 | RSS: Main: 256684032 12:39:01 INFO - PROCESS | 1670 | 12:39:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 12:39:05 INFO - PROCESS | 1670 | RSS: Main: 259706880 12:39:05 INFO - PROCESS | 1670 | 12:39:07 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 12:39:08 INFO - PROCESS | 1670 | RSS: Main: 262090752 12:39:08 INFO - PROCESS | 1670 | 12:39:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:11 INFO - PROCESS | 1670 | RSS: Main: 262193152 12:39:11 INFO - PROCESS | 1670 | 12:39:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:14 INFO - PROCESS | 1670 | RSS: Main: 263438336 12:39:14 INFO - PROCESS | 1670 | 12:39:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:18 INFO - PROCESS | 1670 | RSS: Main: 263610368 12:39:18 INFO - PROCESS | 1670 | 12:39:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 12:39:22 INFO - PROCESS | 1670 | RSS: Main: 263962624 12:39:22 INFO - PROCESS | 1670 | 12:39:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:24 INFO - PROCESS | 1670 | RSS: Main: 263516160 12:39:24 INFO - PROCESS | 1670 | 12:39:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:26 INFO - PROCESS | 1670 | RSS: Main: 263589888 12:39:26 INFO - PROCESS | 1670 | 12:39:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:29 INFO - PROCESS | 1670 | RSS: Main: 263581696 12:39:29 INFO - PROCESS | 1670 | 12:39:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:33 INFO - PROCESS | 1670 | RSS: Main: 264208384 12:39:33 INFO - PROCESS | 1670 | 12:39:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:37 INFO - PROCESS | 1670 | RSS: Main: 264118272 12:39:37 INFO - PROCESS | 1670 | 12:39:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 12:39:39 INFO - PROCESS | 1670 | RSS: Main: 265531392 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | __start_tp_report 12:39:39 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 12:39:39 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 12:39:39 INFO - PROCESS | 1670 | |i|pagename|runs| 12:39:39 INFO - PROCESS | 1670 | |0;ai-astar;164;176;118;117;118;117;119;118;116;119 12:39:39 INFO - PROCESS | 1670 | |1;audio-beat-detection;166;145;113;157;137;144;147;144;171;118 12:39:39 INFO - PROCESS | 1670 | |2;audio-dft;194;214;212;204;195;195;199;194;199;196 12:39:39 INFO - PROCESS | 1670 | |3;audio-fft;82;68;84;109;87;68;60;59;79;61 12:39:39 INFO - PROCESS | 1670 | |4;audio-oscillator;82;93;78;57;58;67;126;100;92;80 12:39:39 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;114;85;97;74;134;88;92;105;136;89 12:39:39 INFO - PROCESS | 1670 | |6;imaging-darkroom;104;98;144;104;96;153;97;97;97;98 12:39:39 INFO - PROCESS | 1670 | |7;imaging-desaturate;73;142;139;88;105;140;149;110;147;117 12:39:39 INFO - PROCESS | 1670 | |8;json-parse-financial;58;57;56;58;59;59;59;58;64;60 12:39:39 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;69;76;71;81;73;70;71;71;71;80 12:39:39 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;102;108;87;88;90;92;104;100;91;87 12:39:39 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;152;152;160;158;152;150;150;149;186;150 12:39:39 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;175;177;176;175;175;177;177;171;228;241 12:39:39 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;70;69;72;71;69;70;70;69;69;70 12:39:39 INFO - PROCESS | 1670 | __end_tp_report 12:39:39 INFO - PROCESS | 1670 | __start_cc_report 12:39:39 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,950 12:39:39 INFO - PROCESS | 1670 | __end_cc_report 12:39:39 INFO - PROCESS | 1670 | __startTimestamp1477251579114__endTimestamp 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | ------- Summary: start ------- 12:39:39 INFO - PROCESS | 1670 | Number of tests: 14 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:128.20 Median:118.50 stddev:22.23 (18.8%) stddev-sans-first:19.44 12:39:39 INFO - PROCESS | 1670 | Values: 164.0 176.0 118.0 117.0 118.0 117.0 119.0 118.0 116.0 119.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:146.00 stddev:18.49 (12.7%) stddev-sans-first:17.85 12:39:39 INFO - PROCESS | 1670 | Values: 166.0 145.0 113.0 157.0 137.0 144.0 147.0 144.0 171.0 118.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:200.20 Median:199.00 stddev:7.42 (3.7%) stddev-sans-first:7.52 12:39:39 INFO - PROCESS | 1670 | Values: 194.0 214.0 212.0 204.0 195.0 195.0 199.0 194.0 199.0 196.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:75.70 Median:73.50 stddev:15.69 (21.3%) stddev-sans-first:16.48 12:39:39 INFO - PROCESS | 1670 | Values: 82.0 68.0 84.0 109.0 87.0 68.0 60.0 59.0 79.0 61.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:79.00 stddev:20.84 (26.4%) stddev-sans-first:22.10 12:39:39 INFO - PROCESS | 1670 | Values: 82.0 93.0 78.0 57.0 58.0 67.0 126.0 100.0 92.0 80.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:101.40 Median:86.50 stddev:20.80 (24.0%) stddev-sans-first:21.55 12:39:39 INFO - PROCESS | 1670 | Values: 114.0 85.0 97.0 74.0 134.0 88.0 92.0 105.0 136.0 89.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:97.00 stddev:21.22 (21.9%) stddev-sans-first:22.44 12:39:39 INFO - PROCESS | 1670 | Values: 104.0 98.0 144.0 104.0 96.0 153.0 97.0 97.0 97.0 98.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:121.00 Median:144.50 stddev:26.61 (18.4%) stddev-sans-first:21.83 12:39:39 INFO - PROCESS | 1670 | Values: 73.0 142.0 139.0 88.0 105.0 140.0 149.0 110.0 147.0 117.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:2.15 (3.6%) stddev-sans-first:2.26 12:39:39 INFO - PROCESS | 1670 | Values: 58.0 57.0 56.0 58.0 59.0 59.0 59.0 58.0 64.0 60.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:72.00 stddev:4.24 (5.9%) stddev-sans-first:4.21 12:39:39 INFO - PROCESS | 1670 | Values: 69.0 76.0 71.0 81.0 73.0 70.0 71.0 71.0 71.0 80.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:87.50 stddev:7.82 (8.9%) stddev-sans-first:7.87 12:39:39 INFO - PROCESS | 1670 | Values: 102.0 108.0 87.0 88.0 90.0 92.0 104.0 100.0 91.0 87.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:152.00 stddev:11.18 (7.4%) stddev-sans-first:11.77 12:39:39 INFO - PROCESS | 1670 | Values: 152.0 152.0 160.0 158.0 152.0 150.0 150.0 149.0 186.0 150.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:177.00 stddev:25.18 (14.2%) stddev-sans-first:26.32 12:39:39 INFO - PROCESS | 1670 | Values: 175.0 177.0 176.0 175.0 175.0 177.0 177.0 171.0 228.0 241.0 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:0.99 (1.4%) stddev-sans-first:1.05 12:39:39 INFO - PROCESS | 1670 | Values: 70.0 69.0 72.0 71.0 69.0 70.0 70.0 69.0 69.0 70.0 12:39:39 INFO - PROCESS | 1670 | -------- Summary: end -------- 12:39:39 INFO - PROCESS | 1670 | 12:39:39 INFO - TEST-INFO | 1670: exit 0 12:39:39 INFO - TEST-OK | kraken | took 60646ms 12:39:39 INFO - SUITE-END | took 1175s 12:39:40 INFO - Completed test suite (00:19:35) 12:39:40 INFO - javascript benchmark 12:39:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7328.179963835054, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2555.9616605750916, 3354.815485148316, 3370.8651653933844, 3378.543896573963, 3431.536742539758, 5738.598298119128, 5790.536757059435, 5804.941950580493, 5824.3010838699365, 5829.825105246839, 5643.492085712295, 5811.186433899247, 5815.069588865782, 5853.239078919734, 5930.940690593104, 5188.221766734991, 5274.208868669692, 5290.0, 5302.416734159239, 5314.4951229633125, 5266.868328291805, 5348.331458567679, 5352.57179425647, 5355.169948657965, 5450.809221677242, 5176.6893986360565, 5296.443873393316, 5301.363836339664, 5333.679979201241, 5342.599305052117, 4270.338097594878, 4403.735775853459, 5202.739863006872, 5263.105272103751, 5367.5169234769055, 5149.253358263069, 5162.251473536354, 5229.869253268661, 5249.973750131263, 5280.234366016906, 4283.464566929134, 5693.430656934315, 5768.394318596529, 5783.132530120473, 5785.4214578541805, 6104.7926496150985, 6123.632582045091, 6141.946315431794, 6224.657643829588, 6228.470580000705, 6258.0, 6260.373962603703, 6272.5922815016875, 6290.245170579514, 6295.401936815995, 5948.40515948406, 5979.372165922559, 5980.282366116094, 5990.822358445061, 6017.849553761103, 5556.972215138899, 5938.406159384027, 6145.187169784924, 6173.59871608344, 6309.0, 6175.0, 6197.070439434031, 6207.782727604603, 6209.379062093754, 6226.25284965807, 6216.875662486725, 6278.372162783685, 6293.779717709858, 6297.748090076438, 6297.874042519215, 6026.668533230627, 6134.895838532271, 6148.44663980244, 6201.162843016135, 6256.405641464054, 6145.078238264206, 6169.074638804125, 6193.4425901669065, 6215.875682486325, 6232.470240029648, 4985.1276026695905, 4988.152014157529, 4989.451160372356, 4995.350604421402, 5030.3963524377305, 4451.0430257494145, 4477.8208871644765, 4488.685791994594, 4494.617957473586, 4495.393121928563, 5745.368009518949, 5778.739956701958, 5872.0, 5885.588008839341, 5938.2280303560265, 6204.689765511706, 6229.626222426669, 6251.874962500725, 6285.151504546919, 6287.622742635456, 5901.3508514063415, 5914.378990206053, 5936.970315148483, 5940.910886336708, 5984.850378740479, 5350.38470575881, 5357.892842143213, 5394.460553944574, 5468.863278418072, 5470.753816078286, 6301.558890877594, 6327.841803954938, 6328.177336946167, 6413.358664133736, 6430.678466076771, 6253.1245625613365, 6256.937430625636, 6258.374162583706, 6266.905996409966, 6268.247810262797, 6166.876662466636, 6381.553291269566, 6391.7123729433215, 6401.231852177582, 6413.685194535015, 6132.01899668756, 6143.419763699347, 6143.631382117087, 6208.192934918432, 6217.813465596039, 6147.354527774519, 6260.311365749855, 6267.529935254783, 6275.968620157053, 6280.183576135256, 4720.669553131248, 5723.912456633227, 6197.752089916353, 6208.9689551551955, 6213.378662133931, 4153.480814898137, 5896.115582662635, 6108.888502348341, 6114.724837382418, 6127.877442451215, 4996.675216110941, 5898.439648233411, 6086.2087928569, 6088.939110609014, 6120.112583675431, 6040.305364883095, 6072.453479186717, 6095.634261944298, 6115.082737589397, 6117.218487018656, 4731.929021064756, 6003.909941350796, 6018.8796224074395, 6021.765538064774, 6026.819195424145], "value": 5685.877670497609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.8133362656256, 531.2562412622328, 532.2787622771145, 535.5367607019928, 540.4595404595404, 38374.99999999996, 39103.60896391039, 40439.79780101099, 40458.78623641288, 42737.14525709484, 41904.79047604765, 42210.0, 42563.36154957678, 42755.786221068855, 43002.0, 8720.040795512488, 8968.417052891542, 9459.668911588113, 9663.806723865519, 9695.563699633534, 9518.904810951906, 9542.952285238563, 9566.760830979247, 9645.951770241174, 9657.082577155194, 8766.0, 8914.86627700585, 9658.03419658032, 9671.226301895831, 9675.612975481008, 9627.711168664917, 9636.421814691106, 9640.807183856354, 9664.420134791935, 9668.854967175497, 9346.299027572959, 9391.483468409235, 9422.104900034486, 9430.10414010668, 9473.715788526355, 39503.60496395028, 40002.999925001815, 40163.99590010244, 40181.0, 40302.798486007676, 3371.6459771724108, 3497.615262321143, 3547.94678079829, 3847.5190601174854, 4222.514410842762, 29980.100596982127, 30685.539716904263, 31203.531947020587, 31247.531287030484, 31305.84347078273, 3123.6251649801943, 3263.0863358259726, 3283.83580820958, 3322.0033989803205, 3323.850426730803, 490002.0, 490316.0, 491458.0, 496019.0, 496327.0, 54199.0, 58334.0, 58580.70709646425, 58806.7059664699, 58983.70508147518, 61530.0, 61742.0, 61866.0720089199, 61973.380266197666, 62022.68988655028, 15659.765103523456, 15912.443064492687, 15924.283407246701, 15932.761008584881, 15955.962862413906, 12274.693132671577, 12287.1399002069, 12350.135490515577, 12358.073144514197, 12402.875971240172, 15501.612459688598, 15986.36054557828, 16042.278097485641, 16058.27737751804, 16066.75899861503, 3184.792988455743, 6743.425984228367, 6988.615626140611, 7004.894926576106, 7120.216776154619, 746.9579935989265, 898.5866501409295, 910.7313342563959, 1061.3260579532052, 1152.7291086594644, 23007.884960575426, 28957.710422895503, 29779.553306700418, 30079.248018799706, 30161.698383016326, 1013.2248829645328, 1022.483645758887, 1066.4987455895716, 1135.3017893995134, 1139.4530625299737, 13669.931650341883, 13949.181164049349, 15367.923160384127, 15481.148536830582, 15759.0, 654113.0, 662770.0, 665843.0, 666985.0, 668755.0, 57277.0, 57329.14006289909, 57999.71000145056, 58003.70998144982, 58145.12782308269, 1329.222404893129, 1367.9452821887014, 1372.876441120304, 1385.861413858606, 1394.9442022318995, 26652.60021099646, 27688.030918917742, 28809.135725928256, 30031.0, 30123.397532049676, 48162.036759263916, 53352.733236333566, 55385.0, 55518.16722749081, 58997.41002589919, 593106.0, 595666.0, 602198.0, 610781.0, 611499.0, 41864.581354187285, 42496.28083908234, 42843.785781070896, 42970.78514607532, 43294.567054329054, 46754.53245467502, 47227.52772472231, 47959.5204047969, 48252.758736206095, 48528.7573562144, 39150.0, 39380.80309598434, 39447.605523944396, 40895.591044089175, 40958.180836383704, 33505.994820155436, 39170.0, 39367.21265574615, 40297.99255018648, 40458.0, 38045.61954380532, 38405.03987400337, 38543.80728096342, 38782.0, 38807.6119238804, 26644.0, 33476.33047339088, 34846.25768711605, 34975.12562185965, 35153.64846351504, 21857.890710546344, 24143.396415089763, 25408.0, 25945.96216151333, 26469.0], "value": 20042.55521032554, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.9383920135574, 320.9277912469693, 343.390481894637, 374.836945928521, 380.305941656477, 18957.33649322274, 19679.311224107158, 20655.896720516415, 20865.582688346225, 21000.159993600275, 16589.585260368498, 20415.0, 20656.277030303903, 20923.849188294636, 20986.475338116554, 4876.829310974116, 4945.752712364385, 5347.866303342409, 5697.772089116441, 5752.856178595548, 4816.54242846929, 5507.0, 5831.766729330843, 5906.61606995546, 5919.2009078774245, 5277.3139491866, 5798.652080875161, 5846.50304724099, 5868.501177399926, 5869.207656966319, 5521.868017056494, 5755.568332375067, 5761.510271626875, 5789.623674461168, 5797.507211886995, 5019.899602007939, 5056.115179843513, 5106.719130447824, 5114.181730923035, 5124.692518448867, 22778.0, 23974.640380394307, 24122.87938560296, 24297.392565185834, 24390.0, 2131.126238242329, 2421.07359824719, 2602.557565213918, 2665.0, 2860.756835668971, 9853.211743060538, 9887.456189909552, 10158.542865571068, 10167.89832101677, 13700.3834827433, 1686.207482483231, 1778.3070358241591, 1832.5235438785878, 1879.1825555883097, 1912.1968773115186, 119842.0, 125318.0, 127830.08254876184, 128128.99999999907, 129006.0, 9366.641836933606, 10309.587616495406, 11089.722756931142, 11405.600803971822, 11470.0, 14397.99214055006, 14430.206338651475, 14449.133052016703, 14453.927730361282, 14474.131552106906, 8097.635606397726, 8317.209865062809, 8449.01448159682, 8471.237588617054, 8477.95761021191, 6795.320467953264, 8114.513129212266, 8150.592470376576, 8257.917420825836, 8505.74482765518, 3375.7974521528786, 4526.456825181, 4544.0, 4553.886152846206, 4625.306204069349, 1896.6586014517243, 1933.5552822850823, 1935.9903200483907, 1953.7557805274341, 1958.6180694764669, 720.0, 751.1136858507015, 917.4632839788665, 947.4457442396275, 978.8433850583872, 20719.0, 21171.576568468543, 21371.46571335729, 21455.892720536296, 21618.919054047234, 926.7358802741321, 948.2650945517141, 948.7153853843819, 953.6900507335005, 961.1974001708512, 10513.89486105129, 11219.551217951353, 11262.08544547429, 11270.887291127148, 11313.83029254562, 581609.0, 582803.0, 588482.0, 600763.0, 603339.0, 130268.0, 131523.0, 134071.61498694998, 134820.0, 136052.31973840066, 841.869510225916, 1007.7581380468635, 1027.3989716016065, 1029.9382037077798, 1030.0832259289389, 18308.08459576989, 18785.436436906915, 18913.621727565645, 18914.43256702301, 18980.43058708241, 53394.73302633617, 53482.93034139373, 53504.0, 53581.0, 55075.0, 74668.0, 76819.23180768121, 78284.60857695903, 79620.20379796128, 82901.58549207215, 7263.782086537412, 7658.846823063619, 7761.301482866569, 7844.058712954482, 7980.8802867955865, 12144.42880647048, 12542.0, 12783.424745886272, 12865.421056052688, 12924.224546527239, 10770.353778773298, 10787.946060269649, 10942.124630029419, 11099.112071034458, 11323.037541809039, 10580.523876425425, 10830.945845270724, 11248.550057997589, 11300.208985371273, 11331.0, 11018.0, 11061.557537698402, 11165.944170279097, 11550.018248448976, 11554.826677600011, 6183.536234782499, 6301.338359472189, 6542.771003015062, 7103.183133939663, 7916.445848790529, 11085.390303533382, 11128.833067503832, 11347.943260283646, 11369.829452558053, 11389.772204556028], "value": 9405.997976140334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.2063813307348, 1599.6240883392402, 1707.4877536738984, 1788.5796837743137, 1905.4569447707388, 7198.0, 7784.96107519463, 8034.116247212802, 8776.736697899065, 8802.339824513156, 8275.255227029582, 8574.614142363593, 8589.01226358969, 8640.91359086409, 8645.0, 1860.4697661166535, 2286.142696488817, 2295.4490922178725, 2297.058206135485, 2302.804261637763, 2241.932742017742, 2275.306031660343, 2298.5632729781373, 2304.0, 2309.203324852923, 2292.587334279829, 2306.08909480755, 2306.6885970394032, 2311.537692461506, 2312.976870231293, 2247.0000849621924, 2252.3243027091808, 2256.0750092462094, 2256.2103263857684, 2258.0516183203094, 2233.1179184222306, 2241.1371621925514, 2249.044156233594, 2255.4699645583273, 2257.288953979491, 8664.913350866475, 8676.8698469523, 8718.694845680371, 8725.694600688945, 8741.825163496695, 5106.157484015133, 5164.173732202867, 5241.606879484016, 5261.789528418859, 5273.367195936512, 3278.1476816027766, 3401.098708842159, 3506.9824650876835, 3516.753827232095, 3523.859045638171, 1021.8160731068405, 1036.0675392147052, 1048.0201012053753, 1052.4001319248032, 1052.752603138262, 10651.946740266327, 10981.231313808037, 10994.725131871688, 11003.284786488932, 11026.44867756617, 4267.317229243306, 4341.0, 4420.823167073346, 4476.462824461086, 4500.3249512573375, 8776.122387761172, 8868.068852770495, 8869.11308869108, 8880.467171969702, 8893.466392016371, 5038.244263360452, 5069.239614057921, 5094.2103973884105, 5097.439281679012, 5105.106606343875, 3953.5453422856162, 4307.353896915488, 4433.733975961452, 4790.281457781291, 4805.471398146201, 3629.1471504196493, 3647.507586475845, 3701.648343407372, 3725.8137093145233, 3733.906652333659, 1121.6018313498591, 1128.9604863829727, 1137.0789660375124, 1138.1009002887627, 1144.8969592736528, 683.3371629519359, 730.936487410816, 893.7006102955582, 916.2852974679761, 938.6057855700623, 5703.0019746544185, 5777.653340799481, 5830.271216097988, 5871.588988770745, 5876.324222714443, 854.5342788180457, 927.888653361601, 958.2046901072093, 961.8938221045713, 992.1566668332005, 3585.1574879903724, 3825.885223443301, 3871.922561548753, 3945.8027098644952, 3961.801909904493, 10222.080012798884, 10531.05220530156, 10536.10443112344, 10543.94728026355, 10559.577616895238, 9453.716388508356, 9900.752481188028, 10381.0, 10492.422916739653, 10532.999365060306, 6748.932510674928, 7881.802954926058, 8025.518468891885, 8037.115917249098, 8043.235892590312, 4616.49218585962, 5748.48263656273, 5789.56578256624, 5881.411858814085, 6161.229846269216, 9365.531723413938, 9379.108984646447, 9463.574139163617, 9479.0, 9502.667406640734, 9195.448273103635, 9522.952385238306, 9525.761855953378, 9668.0, 9692.85460718104, 6953.687084081127, 7135.500514963903, 7234.819129521804, 7329.303716146957, 7493.40052795786, 7990.760277191694, 8070.273675369245, 8076.798080047811, 8078.959605201936, 8087.1104178539135, 7096.325849044332, 7185.209626940928, 7228.530145540523, 7244.529105608119, 7320.743773968099, 7082.0, 7091.432685385053, 7101.964490177516, 7138.643067846483, 7144.857102858018, 6487.967560162169, 6645.0, 6722.563033402813, 6754.628495432602, 6802.251752307341, 4793.592544633745, 4831.9275210871165, 4846.830360937351, 4860.975695121501, 4918.163912134874, 4649.000464900063, 4832.540908613676, 4850.684705494132, 4969.180085285887, 5019.498050194951], "value": 4407.504043440687, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.88073889705214, 296.26082923106844, 304.82320254252534, 306.93861227754456, 309.11592844464826, 5259.631825772188, 5374.596905232113, 5439.891202175959, 5498.285222921019, 5535.474129957647, 4472.686911916167, 5158.277841102239, 5187.922181167285, 5341.732913354336, 5388.335931566533, 4064.4106604542326, 4081.755004771214, 4084.794211126003, 4106.219818234541, 4115.9176816463805, 3807.5430948286244, 4055.148418832034, 4121.248469400508, 4133.9173216535655, 4134.855280065199, 3677.852885884575, 3934.1148241645687, 4061.4110953911663, 4071.7760523171214, 4077.9601201693677, 3311.9834400827963, 3693.298273328059, 4071.42999980003, 4077.67378609712, 4085.7946905662902, 2953.7489313408387, 3547.6807087362263, 3549.5208146900354, 3557.2529768748595, 3562.8931132066077, 4229.6404805591565, 4542.227821270359, 5016.774245158977, 5282.498162674539, 5324.680519168824, 4606.0, 4611.930821037688, 4618.930716039262, 4633.953660463386, 4634.72191668501, 5020.59835213182, 5631.577631677641, 5663.867226554706, 5692.922083439515, 5694.316681998145, 3322.833858307075, 3676.290769345928, 3843.250566139604, 3851.2105018471148, 3854.7879866607327, 5982.341942386334, 5986.73059712314, 6014.65428482892, 6043.969780151115, 6079.969600152015, 3882.1653344530496, 3904.6485816276104, 3909.7458665186673, 3930.665893399036, 3930.901727456779, 3853.6339047790416, 3911.530616326059, 3969.583193764671, 3981.024648961023, 3984.462097616785, 3577.99816051506, 3807.8476860925807, 4340.60934515895, 4538.364628951949, 4637.582617564435, 5223.764930578133, 5692.943070569324, 5747.48272655463, 5776.277965254343, 5794.913076303858, 1050.0864248104199, 1309.7380523895258, 1311.2460335307237, 1325.9138156020022, 1340.9463621455031, 776.825214326772, 855.3969451536652, 905.917428672743, 907.2333877873186, 909.1681111782644, 4575.0, 5269.314989051475, 5303.208206132634, 5375.946240537544, 5378.489043540857, 5792.768289268466, 5820.214271073435, 5825.475707186289, 5833.445822646841, 5890.911636325459, 4662.626989840873, 5018.591076283405, 5075.97462012695, 5276.5251127398715, 5383.650062745987, 2060.1553363121047, 2218.001899145359, 2546.1979476464853, 2553.795696344288, 2563.4621724492617, 4380.255356589388, 4885.682430641997, 5032.974835125801, 5101.642884998088, 5122.641415100908, 4268.509121451073, 4843.588294994965, 5032.445636698793, 5654.547636189095, 6125.387461253839, 6189.122058242939, 6196.504279657706, 6205.627662340184, 6232.974283357927, 6245.78207249591, 5390.706230504729, 5589.469000444951, 5990.580659353804, 6012.669303188367, 6044.184035155197, 4683.929741053818, 5162.432132465351, 5307.65500242483, 5616.770357044634, 5778.27771528559, 6197.628142311476, 6204.844878878064, 6233.875322493615, 6245.7189426476825, 6287.559870809182, 3828.980855095707, 3892.630200130983, 3925.134651704215, 3939.409088636613, 3949.7235193537326, 5524.558035357081, 5603.422425684231, 5813.24427824397, 5975.671338076274, 5993.940060599338, 5198.324217851655, 5518.255035570147, 5642.830715078554, 5671.177679236405, 5762.349319313163, 4720.173969555382, 5732.398098199796, 5756.251687280626, 5763.7982670606325, 5774.624649397776, 4919.532644398776, 5201.71390573507, 5250.789968401221, 5376.086065368819, 5660.632058916157, 5762.250907382014, 5773.3072031356505, 5784.566157538117, 5795.8551036224435, 5805.332386775575, 5482.862928426821, 5607.607467477237, 5665.376808551138, 5676.631018983918, 5692.829215123553], "value": 4093.862980339896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.3204151661513, 692.4425837201057, 693.4943858793798, 776.3207193705508, 834.5076404921098, 12916.225026498416, 12956.48174073037, 15012.249387530634, 16256.268467918942, 16276.430324938614, 14600.92699536501, 14858.33137508812, 14969.326380312885, 15067.77398339023, 15069.910668312998, 894.9239314658262, 904.5612877754284, 905.6739573753443, 908.2325435007408, 908.6774195160722, 700.6216643012767, 721.8051126195924, 872.650939624149, 900.6082354175954, 903.9819203615924, 932.6316105138466, 958.832204364237, 968.0, 968.138356862393, 970.1462712812715, 942.0767647705219, 960.4237457525498, 962.311946957925, 963.0562049191761, 967.472727363585, 712.2841544247998, 714.8534550417204, 715.2668514772348, 715.5420530860254, 717.6124892558065, 15663.21683915811, 15744.527664170093, 15914.442994495254, 15918.840811591852, 15920.124393158372, 14580.489682861155, 14599.635009124751, 14810.407583696639, 14851.925740371229, 14919.254037298091, 11824.645260642108, 11843.763124737543, 11997.700057498632, 12063.577774777928, 12219.877801221963, 3700.666939975415, 3727.7390582659223, 3877.3602355611292, 3916.3929590913453, 3922.2547715934165, 113276.43361783221, 114755.42622286835, 115485.42257288744, 116733.832661674, 117091.0, 10956.56173753041, 10997.725056873642, 11181.097646189795, 11294.983451489405, 11386.829197562043, 23375.0, 23395.0, 23401.478903871193, 23401.88299058528, 23437.88281058618, 7847.039449135072, 8008.719694810654, 8102.432829701865, 8160.224778646019, 8241.711540096068, 4311.137772445523, 4356.738595684269, 4373.995588073817, 4436.467623885154, 4501.774911254424, 3457.7752446091426, 3589.174489867293, 3594.748367614242, 3595.658412450813, 3603.94594081089, 553.97784088636, 628.1896353703762, 635.0696230023038, 660.1352228580574, 664.0039940089865, 568.9731224630436, 585.3911931591183, 646.9971544106616, 671.5342969254497, 674.2650510943096, 8096.311813495919, 8530.360222983301, 9047.276217902552, 9257.213136883305, 9332.113449222314, 644.6196743921109, 662.7150325360045, 675.2774531251514, 679.4666187043148, 680.7549282258383, 3804.4863943367845, 4078.3066878630702, 4211.852585159566, 4730.668853180313, 4847.345608342828, 94383.0561694388, 125129.37435312766, 147175.0, 155213.0, 163560.0, 111976.0, 137429.5028198881, 138494.3075284617, 139503.0, 142494.0, 102382.48808755909, 103506.48246758869, 108933.0, 124253.0, 124972.0, 9708.951455242679, 9802.705918822447, 9874.151986062554, 9975.251856110966, 10235.692929212135, 64365.35634643594, 69335.65332173307, 69740.65129674319, 70480.64759676169, 71282.28717712757, 97260.0, 106676.46661766642, 107307.0, 107518.46240768746, 108099.6760129586, 14128.858711413166, 14229.573112806633, 14298.0, 14502.0, 14616.0, 14750.852491474949, 14911.925440372728, 15048.322825472662, 15609.0, 15630.140342281284, 12562.685932851751, 13028.739425211254, 14046.438142474186, 14107.224102674041, 14625.122492650049, 13533.961916431306, 13581.592552223448, 13594.252316122709, 13609.727805443637, 13742.0, 13512.189268643913, 13627.773500385012, 13840.0, 13868.514601988882, 14290.785638215228, 11062.280951738112, 11107.222494425636, 11337.0, 11448.599299024494, 11987.34069626179, 9061.54692265371, 10445.373277603368, 10826.729331766768, 11437.42812859337, 11703.23928944616], "value": 8006.593212250148, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 176.0, 118.0, 117.0, 118.0, 117.0, 119.0, 118.0, 116.0, 119.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 145.0, 113.0, 157.0, 137.0, 144.0, 147.0, 144.0, 171.0, 118.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 214.0, 212.0, 204.0, 195.0, 195.0, 199.0, 194.0, 199.0, 196.0], "value": 200.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 84.0, 109.0, 87.0, 68.0, 60.0, 59.0, 79.0, 61.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 93.0, 78.0, 57.0, 58.0, 67.0, 126.0, 100.0, 92.0, 80.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 85.0, 97.0, 74.0, 134.0, 88.0, 92.0, 105.0, 136.0, 89.0], "value": 101.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 98.0, 144.0, 104.0, 96.0, 153.0, 97.0, 97.0, 97.0, 98.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 142.0, 139.0, 88.0, 105.0, 140.0, 149.0, 110.0, 147.0, 117.0], "value": 121.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 58.0, 59.0, 59.0, 59.0, 58.0, 64.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 71.0, 81.0, 73.0, 70.0, 71.0, 71.0, 71.0, 80.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 87.0, 88.0, 90.0, 92.0, 104.0, 100.0, 91.0, 87.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 160.0, 158.0, 152.0, 150.0, 150.0, 149.0, 186.0, 150.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 176.0, 175.0, 175.0, 177.0, 177.0, 171.0, 228.0, 241.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 72.0, 71.0, 69.0, 70.0, 70.0, 69.0, 69.0, 70.0], "value": 69.9, "unit": "score"}]}]} 12:39:40 INFO - Return code: 0 12:39:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:39:40 ERROR - # TBPL SUCCESS # 12:39:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:40 INFO - 2016-10-23 12:39:40.442 screenresolution[1675:10484] starting screenresolution argv=screenresolution get 12:39:40 INFO - 2016-10-23 12:39:40.445 screenresolution[1675:10484] Display 0: 1600x1200x32@60 12:39:40 INFO - 2016-10-23 12:39:40.451 screenresolution[1676:10487] starting screenresolution argv=screenresolution list 12:39:40 INFO - Available Modes on Display 0 12:39:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:40 INFO - Intel Iris: 12:39:40 INFO - Chipset Model: Intel Iris 12:39:40 INFO - Type: GPU 12:39:40 INFO - Bus: Built-In 12:39:40 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:40 INFO - Vendor: Intel (0x8086) 12:39:40 INFO - Device ID: 0x0a2e 12:39:40 INFO - Revision ID: 0x0009 12:39:40 INFO - Displays: 12:39:40 INFO - Display: 12:39:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:40 INFO - Display Serial Number: 1600x1200 60 12:39:40 INFO - Main Display: Yes 12:39:40 INFO - Mirror: Off 12:39:40 INFO - Online: Yes 12:39:40 INFO - Rotation: Supported 12:39:40 INFO - Return code: 0 12:39:40 INFO - Running post-action listener: _resource_record_post_action 12:39:40 INFO - [mozharness: 2016-10-23 19:39:40.685640Z] Finished run-tests step (success) 12:39:40 INFO - Running post-run listener: _resource_record_post_run 12:39:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38173184; Write bytes: 709660160; Read time: 244; Write time: 1978 12:39:40 INFO - TinderboxPrint: CPU usage
54.4% 12:39:40 INFO - TinderboxPrint: I/O read bytes / time
38,173,184 / 244 12:39:40 INFO - TinderboxPrint: I/O write bytes / time
709,660,160 / 1,978 12:39:40 INFO - TinderboxPrint: CPU idle
2,210.2 (45.6%) 12:39:40 INFO - TinderboxPrint: CPU system
976.7 (20.2%) 12:39:40 INFO - TinderboxPrint: CPU user
1,658.7 (34.2%) 12:39:40 INFO - TinderboxPrint: Swap in / out
443,379,712 / 0 12:39:40 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 409600000; Write bytes: 405116928; Read time: 30589; Write time: 828 12:39:40 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29794816; Write bytes: 300439040; Read time: 178; Write time: 1129 12:39:41 INFO - Running post-run listener: _upload_blobber_files 12:39:41 INFO - Blob upload gear active. 12:39:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:39:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:39:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:39:41 INFO - (blobuploader) - INFO - Open directory for files ... 12:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:39:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:42 INFO - (blobuploader) - INFO - Done attempting. 12:39:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:39:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:39:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:43 INFO - (blobuploader) - INFO - Done attempting. 12:39:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:39:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:45 INFO - (blobuploader) - INFO - Done attempting. 12:39:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:39:45 INFO - Return code: 0 12:39:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:39:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:39:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4195e2012c7dddfbf75d5a0e09af5a115d3d9d201d675402b60168dc4be0cd739298048be87e32a80838f3cd2f30747368559ff5c21b95337c251e9d9379de72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c80d78c5c397798de9aa8124a8fbbf817dfe5ac07259197f439cc50e73fc354af7e23d3cb15586c12225bc04315b48d5f1662f5d5edcde01ad662e59ff59c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e4db31300be1e304423dbc077d9e458b834e62d18deb1962354a5a3084b6920d2c0d07214b657158facaaaecc307fa76561fdca8c0749a833fc5e1397fc854"} 12:39:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:39:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:39:45 INFO - Contents: 12:39:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4195e2012c7dddfbf75d5a0e09af5a115d3d9d201d675402b60168dc4be0cd739298048be87e32a80838f3cd2f30747368559ff5c21b95337c251e9d9379de72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c80d78c5c397798de9aa8124a8fbbf817dfe5ac07259197f439cc50e73fc354af7e23d3cb15586c12225bc04315b48d5f1662f5d5edcde01ad662e59ff59c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e4db31300be1e304423dbc077d9e458b834e62d18deb1962354a5a3084b6920d2c0d07214b657158facaaaecc307fa76561fdca8c0749a833fc5e1397fc854"} 12:39:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:39:45 INFO - Copying logs to upload dir... 12:39:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:39:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.451355 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-23 12:39:45.691552) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 12:39:45.699005) ========= 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.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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/4195e2012c7dddfbf75d5a0e09af5a115d3d9d201d675402b60168dc4be0cd739298048be87e32a80838f3cd2f30747368559ff5c21b95337c251e9d9379de72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c80d78c5c397798de9aa8124a8fbbf817dfe5ac07259197f439cc50e73fc354af7e23d3cb15586c12225bc04315b48d5f1662f5d5edcde01ad662e59ff59c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e4db31300be1e304423dbc077d9e458b834e62d18deb1962354a5a3084b6920d2c0d07214b657158facaaaecc307fa76561fdca8c0749a833fc5e1397fc854"} build_url:https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011002 build_url: 'https://queue.taskcluster.net/v1/task/fl3dUSxGT-CX2HZ7cZMNlQ/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/4195e2012c7dddfbf75d5a0e09af5a115d3d9d201d675402b60168dc4be0cd739298048be87e32a80838f3cd2f30747368559ff5c21b95337c251e9d9379de72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0c80d78c5c397798de9aa8124a8fbbf817dfe5ac07259197f439cc50e73fc354af7e23d3cb15586c12225bc04315b48d5f1662f5d5edcde01ad662e59ff59c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e4db31300be1e304423dbc077d9e458b834e62d18deb1962354a5a3084b6920d2c0d07214b657158facaaaecc307fa76561fdca8c0749a833fc5e1397fc854"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 12:39:45.730406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:39:45.733717) ========= 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.wKS7nOOXSP/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.LdDlfYn34e/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.004970 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 12:39:45.782507) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 12:39:45.785836) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 12:39:45.786150) ========= ========= Total master_lag: 0.21 =========