builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0075 starttime: 1486771038.36 results: success (0) revision: 855e6b2f6199189f37cea093cbdd1735e297e8aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.360586) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.360990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.361274) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.394065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.394349) ========= 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.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 --2017-02-10 15:57:18-- 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% 66.4M=0s 2017-02-10 15:57:18 (66.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135793 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.562298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.562812) ========= 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.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.028331 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:18.620884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-10 15:57:18.621235) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 855e6b2f6199189f37cea093cbdd1735e297e8aa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 855e6b2f6199189f37cea093cbdd1735e297e8aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 2017-02-10 15:57:18,723 truncating revision to first 12 chars 2017-02-10 15:57:18,723 Setting DEBUG logging. 2017-02-10 15:57:18,723 attempt 1/10 2017-02-10 15:57:18,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/855e6b2f6199?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-10 15:57:20,726 unpacking tar archive at: mozilla-central-855e6b2f6199/testing/mozharness/ program finished with exit code 0 elapsedTime=2.277969 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-10 15:57:20.924664) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:20.925288) ========= script_repo_revision: 855e6b2f6199189f37cea093cbdd1735e297e8aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:20.925953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:20.926459) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 15:57:20.947819) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-02-10 15:57:20.948325) ========= /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.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:57:21 INFO - MultiFileLogger online at 20170210 15:57:21 in /builds/slave/test 15:57:21 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 15:57:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:57:21 INFO - {'append_to_log': False, 15:57:21 INFO - 'base_work_dir': '/builds/slave/test', 15:57:21 INFO - 'blob_upload_branch': 'Firefox', 15:57:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:57:21 INFO - 'branch': 'Firefox', 15:57:21 INFO - 'buildbot_json_path': 'buildprops.json', 15:57:21 INFO - 'config_files': ('talos/mac_config.py',), 15:57:21 INFO - 'default_actions': ('clobber', 15:57:21 INFO - 'read-buildbot-config', 15:57:21 INFO - 'download-and-extract', 15:57:21 INFO - 'populate-webroot', 15:57:21 INFO - 'create-virtualenv', 15:57:21 INFO - 'install', 15:57:21 INFO - 'run-tests'), 15:57:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:57:21 INFO - 'download_minidump_stackwalk': True, 15:57:21 INFO - 'download_symbols': 'ondemand', 15:57:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:57:21 INFO - 'tooltool.py': '/tools/tooltool.py', 15:57:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:57:21 INFO - '/tools/misc-python/virtualenv.py')}, 15:57:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:57:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:57:21 INFO - 'installer_path': 'installer.exe', 15:57:21 INFO - 'log_level': 'info', 15:57:21 INFO - 'log_name': 'talos', 15:57:21 INFO - 'log_to_console': True, 15:57:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:57:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:57:21 INFO - 'opt_config_files': (), 15:57:21 INFO - 'pip_index': False, 15:57:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:21 INFO - 'cmd': ('bash', 15:57:21 INFO - '-c', 15:57:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:21 INFO - 'enabled': True, 15:57:21 INFO - 'halt_on_failure': False, 15:57:21 INFO - 'name': 'check_screen_resolution'},), 15:57:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:21 INFO - 'cmd': ('bash', 15:57:21 INFO - '-c', 15:57:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:21 INFO - 'enabled': True, 15:57:21 INFO - 'halt_on_failure': False, 15:57:21 INFO - 'name': 'check_screen_resolution'},), 15:57:21 INFO - 'run_cmd_checks_enabled': True, 15:57:21 INFO - 'sps_profile': False, 15:57:21 INFO - 'sps_profile_interval': 0, 15:57:21 INFO - 'suite': 'dromaeojs-e10s', 15:57:21 INFO - 'system_bits': '32', 15:57:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:57:21 INFO - 'title': 't-yosemite-r7-0075', 15:57:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:57:21 INFO - 'use_talos_json': True, 15:57:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:57:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:57:21 INFO - 'work_dir': 'build'} 15:57:21 INFO - [mozharness: 2017-02-10 23:57:21.164841Z] Running clobber step. 15:57:21 INFO - Running pre-action listener: _resource_record_pre_action 15:57:21 INFO - Running main action method: clobber 15:57:21 INFO - rmtree: /builds/slave/test/build 15:57:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:57:23 INFO - Running post-action listener: _resource_record_post_action 15:57:23 INFO - [mozharness: 2017-02-10 23:57:23.796026Z] Finished clobber step (success) 15:57:23 INFO - [mozharness: 2017-02-10 23:57:23.796134Z] Running read-buildbot-config step. 15:57:23 INFO - Running pre-action listener: _resource_record_pre_action 15:57:23 INFO - Running main action method: read_buildbot_config 15:57:23 INFO - Using buildbot properties: 15:57:23 INFO - { 15:57:23 INFO - "project": "", 15:57:23 INFO - "product": "firefox", 15:57:23 INFO - "who": "kwierso@gmail.com", 15:57:23 INFO - "installer_path": "public/build/target.dmg", 15:57:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:57:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:57:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:57:23 INFO - "stage_platform": "macosx64", 15:57:23 INFO - "basedir": "/builds/slave/test", 15:57:23 INFO - "buildnumber": 82, 15:57:23 INFO - "platform": "yosemite_r7", 15:57:23 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:57:23 INFO - "slavebuilddir": "test", 15:57:23 INFO - "taskId": "XPjirLhETK6bbwjib21BXw", 15:57:23 INFO - "branch": "mozilla-central", 15:57:23 INFO - "script_repo_revision": "production", 15:57:23 INFO - "revision": "855e6b2f6199189f37cea093cbdd1735e297e8aa", 15:57:23 INFO - "slavename": "t-yosemite-r7-0075", 15:57:23 INFO - "repo_path": "mozilla-central" 15:57:23 INFO - } 15:57:23 INFO - Finding installer, test and symbols from parent task. 15:57:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XPjirLhETK6bbwjib21BXw'}, attempt #1 15:57:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XPjirLhETK6bbwjib21BXw'}, attempt #1 15:57:24 INFO - Task dependencies: dxCbUAReT-6sFVFfp4TIbw 15:57:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw'}, attempt #1 15:57:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg 15:57:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/test_packages.json 15:57:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.crashreporter-symbols.zip 15:57:25 INFO - Running post-action listener: _resource_record_post_action 15:57:25 INFO - [mozharness: 2017-02-10 23:57:25.163956Z] Finished read-buildbot-config step (success) 15:57:25 INFO - [mozharness: 2017-02-10 23:57:25.164076Z] Running download-and-extract step. 15:57:25 INFO - Running pre-action listener: _resource_record_pre_action 15:57:25 INFO - Running main action method: download_and_extract 15:57:25 INFO - mkdir: /builds/slave/test/build/tests 15:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:25 INFO - trying https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.test_packages.json 15:57:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:57:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:57:26 INFO - Downloaded 896 bytes. 15:57:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:57:26 INFO - Using the following test package requirements: 15:57:26 INFO - {u'common': [u'target.common.tests.zip'], 15:57:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:57:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:57:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:57:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:57:26 INFO - u'mozbase': [u'target.common.tests.zip'], 15:57:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:57:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:57:26 INFO - u'web-platform': [u'target.common.tests.zip', 15:57:26 INFO - u'target.web-platform.tests.zip'], 15:57:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:57:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:57:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip 15:57:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:57:26 INFO - Fetch https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip into memory 15:57:27 INFO - Expected file size: 34361316 15:57:27 INFO - Obtained file size: 34361316 15:57:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:57:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip 15:57:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:57:34 INFO - Fetch https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.common.tests.zip into memory 15:57:35 INFO - Expected file size: 34361316 15:57:35 INFO - Obtained file size: 34361316 15:57:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.talos.tests.zip 15:57:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:57:41 INFO - Fetch https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.talos.tests.zip into memory 15:57:43 INFO - Expected file size: 14403513 15:57:43 INFO - Obtained file size: 14403513 15:57:43 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')]} 15:57:43 INFO - trying https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg 15:57:43 INFO - Downloading https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg to /builds/slave/test/build/target.dmg 15:57:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg', 'file_name': '/builds/slave/test/build/target.dmg'}, attempt #1 15:57:45 INFO - Downloaded 63918921 bytes. 15:57:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg 15:57:45 INFO - mkdir: /builds/slave/test/properties 15:57:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:57:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:57:45 INFO - Contents: 15:57:45 INFO - build_url:https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg 15:57:45 INFO - Running post-action listener: _resource_record_post_action 15:57:45 INFO - Running post-action listener: set_extra_try_arguments 15:57:45 INFO - [mozharness: 2017-02-10 23:57:45.453144Z] Finished download-and-extract step (success) 15:57:45 INFO - [mozharness: 2017-02-10 23:57:45.453296Z] Running populate-webroot step. 15:57:45 INFO - Running pre-action listener: _resource_record_pre_action 15:57:45 INFO - Running main action method: populate_webroot 15:57:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:57:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:57:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:57:45 INFO - '--tppagecycles', 15:57:45 INFO - '7'], 15:57:45 INFO - 'tests': ['tsvgm']}}, 15:57:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:57:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:57:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:57:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:57:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['damp', 'tps']}, 15:57:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'tests': ['damp', 'tps']}, 15:57:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['dromaeo_dom']}, 15:57:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:57:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['basic_compositor_video']}, 15:57:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:57:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'other-e10s': {'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['a11yr', 15:57:45 INFO - 'ts_paint', 15:57:45 INFO - 'tpaint', 15:57:45 INFO - 'sessionrestore', 15:57:45 INFO - 'sessionrestore_no_auto_restore', 15:57:45 INFO - 'tabpaint']}, 15:57:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['tsvgx', 15:57:45 INFO - 'tsvgr_opacity', 15:57:45 INFO - 'tart', 15:57:45 INFO - 'tscrollx', 15:57:45 INFO - 'cart', 15:57:45 INFO - 'tsvg_static']}, 15:57:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:57:45 INFO - 'tsvgr_opacity', 15:57:45 INFO - 'tart', 15:57:45 INFO - 'tscrollx', 15:57:45 INFO - 'cart', 15:57:45 INFO - 'tsvg_static']}, 15:57:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'talos_options': ['--disable-e10s'], 15:57:45 INFO - 'tests': ['tp5o']}, 15:57:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:57:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'talos_options': ['--disable-e10s', 15:57:45 INFO - '--xperf_path', 15:57:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:45 INFO - 'tests': ['tp5n']}, 15:57:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:57:45 INFO - 'talos_options': ['--xperf_path', 15:57:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:45 INFO - 'tests': ['tp5n']}}, 15:57:45 INFO - 'talos.zip': {'path': '', 15:57:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:57:45 INFO - Running post-action listener: _resource_record_post_action 15:57:45 INFO - [mozharness: 2017-02-10 23:57:45.473923Z] Finished populate-webroot step (success) 15:57:45 INFO - [mozharness: 2017-02-10 23:57:45.474032Z] Running create-virtualenv step. 15:57:45 INFO - Running pre-action listener: _resource_record_pre_action 15:57:45 INFO - Running main action method: create_virtualenv 15:57:45 INFO - Creating virtualenv /builds/slave/test/build/venv 15:57:45 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 15:57:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:57:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:57:45 INFO - Using real prefix '/tools/python27' 15:57:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:57:46 INFO - Installing distribute.............................................................................................................................................................................................done. 15:57:49 INFO - Installing pip.................done. 15:57:49 INFO - Return code: 0 15:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:57:49 INFO - Reading from file tmpfile_stdout 15:57:49 INFO - Output received: 15:57:49 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) 15:57:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:57:49 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')]} 15:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:49 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')]} 15:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:49 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:57:49 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 15:57:49 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 15:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:57:49 INFO - 'HOME': '/Users/cltbld', 15:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:57:49 INFO - 'LOGNAME': 'cltbld', 15:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:49 INFO - 'MOZ_NO_REMOTE': '1', 15:57:49 INFO - 'NO_EM_RESTART': '1', 15:57:49 INFO - 'PAGER': '/bin/cat', 15:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:49 INFO - 'PWD': '/builds/slave/test', 15:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:57:49 INFO - 'SHELL': '/bin/bash', 15:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:57:49 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:57:49 INFO - 'USER': 'cltbld', 15:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:49 INFO - 'XPC_FLAGS': '0x0', 15:57:49 INFO - 'XPC_SERVICE_NAME': '0', 15:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:49 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 15:57:49 INFO - Cleaning up... 15:57:49 INFO - Return code: 0 15:57:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:57:49 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')]} 15:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:49 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')]} 15:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:49 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:57:49 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 15:57:49 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 15:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:57:49 INFO - 'HOME': '/Users/cltbld', 15:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:57:49 INFO - 'LOGNAME': 'cltbld', 15:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:49 INFO - 'MOZ_NO_REMOTE': '1', 15:57:49 INFO - 'NO_EM_RESTART': '1', 15:57:49 INFO - 'PAGER': '/bin/cat', 15:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:49 INFO - 'PWD': '/builds/slave/test', 15:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:57:49 INFO - 'SHELL': '/bin/bash', 15:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:57:49 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:57:49 INFO - 'USER': 'cltbld', 15:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:49 INFO - 'XPC_FLAGS': '0x0', 15:57:49 INFO - 'XPC_SERVICE_NAME': '0', 15:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:50 INFO - Downloading/unpacking psutil>=3.1.1 15:57:50 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 15:57:50 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 15:57:50 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 15:57:50 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 15:57:50 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 15:57:50 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 15:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:57:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:53 INFO - Installing collected packages: psutil 15:57:53 INFO - Running setup.py install for psutil 15:57:53 INFO - building 'psutil._psutil_osx' extension 15:57:53 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 15:57:54 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 15:57:54 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 15:57:54 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 15:57:54 INFO - building 'psutil._psutil_posix' extension 15:57:54 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 15:57:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:57:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:57:54 INFO - ^ 15:57:54 INFO - 1 warning generated. 15:57:54 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 15:57:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:54 INFO - Successfully installed psutil 15:57:54 INFO - Cleaning up... 15:57:54 INFO - Return code: 0 15:57:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:57:54 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')]} 15:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:54 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')]} 15:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:54 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:57:54 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 15:57:54 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 15:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:57:54 INFO - 'HOME': '/Users/cltbld', 15:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:57:54 INFO - 'LOGNAME': 'cltbld', 15:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:54 INFO - 'MOZ_NO_REMOTE': '1', 15:57:54 INFO - 'NO_EM_RESTART': '1', 15:57:54 INFO - 'PAGER': '/bin/cat', 15:57:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:54 INFO - 'PWD': '/builds/slave/test', 15:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:57:54 INFO - 'SHELL': '/bin/bash', 15:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:57:54 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:57:54 INFO - 'USER': 'cltbld', 15:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:54 INFO - 'XPC_FLAGS': '0x0', 15:57:54 INFO - 'XPC_SERVICE_NAME': '0', 15:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:57:54 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 15:57:54 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 15:57:54 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 15:57:54 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 15:57:54 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 15:57:54 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 15:57:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:57:57 INFO - Installing collected packages: mozsystemmonitor 15:57:57 INFO - Running setup.py install for mozsystemmonitor 15:57:57 INFO - Successfully installed mozsystemmonitor 15:57:57 INFO - Cleaning up... 15:57:57 INFO - Return code: 0 15:57:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:57 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:57:57 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 15:57:57 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 15:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:57:57 INFO - 'HOME': '/Users/cltbld', 15:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:57:57 INFO - 'LOGNAME': 'cltbld', 15:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:57 INFO - 'MOZ_NO_REMOTE': '1', 15:57:57 INFO - 'NO_EM_RESTART': '1', 15:57:57 INFO - 'PAGER': '/bin/cat', 15:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:57 INFO - 'PWD': '/builds/slave/test', 15:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:57:57 INFO - 'SHELL': '/bin/bash', 15:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:57:57 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:57:57 INFO - 'USER': 'cltbld', 15:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:57 INFO - 'XPC_FLAGS': '0x0', 15:57:57 INFO - 'XPC_SERVICE_NAME': '0', 15:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:58 INFO - Downloading/unpacking jsonschema==2.5.1 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:58:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:58:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58:01 INFO - Downloading functools32-3.2.3-2.tar.gz 15:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:58:01 INFO - warning: no files found matching '*.txt' 15:58:01 INFO - no previously-included directories found matching 'build' 15:58:01 INFO - no previously-included directories found matching 'dist' 15:58:01 INFO - no previously-included directories found matching '.git*' 15:58:01 INFO - Installing collected packages: jsonschema, functools32 15:58:01 INFO - Running setup.py install for functools32 15:58:01 INFO - warning: no files found matching '*.txt' 15:58:01 INFO - no previously-included directories found matching 'build' 15:58:01 INFO - no previously-included directories found matching 'dist' 15:58:01 INFO - no previously-included directories found matching '.git*' 15:58:01 INFO - Successfully installed jsonschema functools32 15:58:01 INFO - Cleaning up... 15:58:01 INFO - Return code: 0 15:58:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:01 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:01 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 15:58:01 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 15:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:01 INFO - 'HOME': '/Users/cltbld', 15:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:01 INFO - 'LOGNAME': 'cltbld', 15:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:01 INFO - 'MOZ_NO_REMOTE': '1', 15:58:01 INFO - 'NO_EM_RESTART': '1', 15:58:01 INFO - 'PAGER': '/bin/cat', 15:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:01 INFO - 'PWD': '/builds/slave/test', 15:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:01 INFO - 'SHELL': '/bin/bash', 15:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:01 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:01 INFO - 'USER': 'cltbld', 15:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:01 INFO - 'XPC_FLAGS': '0x0', 15:58:01 INFO - 'XPC_SERVICE_NAME': '0', 15:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:58:01 INFO - Cleaning up... 15:58:01 INFO - Return code: 0 15:58:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:01 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:01 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 15:58:01 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 15:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:01 INFO - 'HOME': '/Users/cltbld', 15:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:01 INFO - 'LOGNAME': 'cltbld', 15:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:01 INFO - 'MOZ_NO_REMOTE': '1', 15:58:01 INFO - 'NO_EM_RESTART': '1', 15:58:01 INFO - 'PAGER': '/bin/cat', 15:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:01 INFO - 'PWD': '/builds/slave/test', 15:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:01 INFO - 'SHELL': '/bin/bash', 15:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:01 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:01 INFO - 'USER': 'cltbld', 15:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:01 INFO - 'XPC_FLAGS': '0x0', 15:58:01 INFO - 'XPC_SERVICE_NAME': '0', 15:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:01 INFO - Downloading/unpacking blobuploader==1.2.4 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:04 INFO - Downloading blobuploader-1.2.4.tar.gz 15:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:58:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:58:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58:05 INFO - Downloading docopt-0.6.1.tar.gz 15:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:58:05 INFO - Installing collected packages: blobuploader, requests, docopt 15:58:05 INFO - Running setup.py install for blobuploader 15:58:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:58:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:58:06 INFO - Running setup.py install for requests 15:58:06 INFO - Running setup.py install for docopt 15:58:06 INFO - Successfully installed blobuploader requests docopt 15:58:06 INFO - Cleaning up... 15:58:06 INFO - Return code: 0 15:58:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:06 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:06 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 15:58:06 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 15:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:06 INFO - 'HOME': '/Users/cltbld', 15:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:06 INFO - 'LOGNAME': 'cltbld', 15:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:06 INFO - 'MOZ_NO_REMOTE': '1', 15:58:06 INFO - 'NO_EM_RESTART': '1', 15:58:06 INFO - 'PAGER': '/bin/cat', 15:58:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:06 INFO - 'PWD': '/builds/slave/test', 15:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:06 INFO - 'SHELL': '/bin/bash', 15:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:06 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:06 INFO - 'USER': 'cltbld', 15:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:06 INFO - 'XPC_FLAGS': '0x0', 15:58:06 INFO - 'XPC_SERVICE_NAME': '0', 15:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-JaBQb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-LMJX8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-CiewnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-EYlvVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-2rnOFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-NFDam3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-RGX4Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-LQYOPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-axySrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Iq5WbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-hRcUyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-5SHlZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-7H2Rlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-GSUW7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Cn97xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-qtwLwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-sZ2nez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:58:08 INFO - Running setup.py install for manifestparser 15:58:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:58:09 INFO - Running setup.py install for mozcrash 15:58:09 INFO - Running setup.py install for mozdebug 15:58:09 INFO - Running setup.py install for mozdevice 15:58:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:58:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:58:09 INFO - Running setup.py install for mozfile 15:58:09 INFO - Running setup.py install for mozhttpd 15:58:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:58:09 INFO - Running setup.py install for mozinfo 15:58:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:58:09 INFO - Running setup.py install for mozInstall 15:58:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Running setup.py install for mozleak 15:58:10 INFO - Running setup.py install for mozlog 15:58:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Running setup.py install for moznetwork 15:58:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Running setup.py install for mozprocess 15:58:10 INFO - Running setup.py install for mozprofile 15:58:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:58:10 INFO - Running setup.py install for mozrunner 15:58:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:58:11 INFO - Running setup.py install for mozscreenshot 15:58:11 INFO - Running setup.py install for moztest 15:58:11 INFO - Running setup.py install for mozversion 15:58:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:58:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:58:11 INFO - Cleaning up... 15:58:11 INFO - Return code: 0 15:58:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:11 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:11 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 15:58:11 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 15:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:11 INFO - 'HOME': '/Users/cltbld', 15:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:11 INFO - 'LOGNAME': 'cltbld', 15:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:11 INFO - 'MOZ_NO_REMOTE': '1', 15:58:11 INFO - 'NO_EM_RESTART': '1', 15:58:11 INFO - 'PAGER': '/bin/cat', 15:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:11 INFO - 'PWD': '/builds/slave/test', 15:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:11 INFO - 'SHELL': '/bin/bash', 15:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:11 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:11 INFO - 'USER': 'cltbld', 15:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:11 INFO - 'XPC_FLAGS': '0x0', 15:58:11 INFO - 'XPC_SERVICE_NAME': '0', 15:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-doHnTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:11 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)) 15:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-SrWE40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Y2MmS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-WFd3aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-XWvoMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-VdqqA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-hG8GIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ruZ6Wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-TE1gwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-X1AkpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-JMyppD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:12 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)) 15:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-36CyoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 15:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ldoclT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:13 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)) 15:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-CA0D7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-nXf_0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:13 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)) 15:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ttnBXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Q2dK6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:13 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)) 15:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:58:13 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)) 15:58:13 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:58:13 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:58:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:16 INFO - Downloading blessings-1.6.tar.gz 15:58:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:58:16 INFO - Installing collected packages: blessings 15:58:16 INFO - Running setup.py install for blessings 15:58:16 INFO - Successfully installed blessings 15:58:16 INFO - Cleaning up... 15:58:16 INFO - Return code: 0 15:58:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:17 INFO - Reading from file tmpfile_stdout 15:58:17 INFO - Current package versions: 15:58:17 INFO - blessings == 1.6 15:58:17 INFO - blobuploader == 1.2.4 15:58:17 INFO - docopt == 0.6.1 15:58:17 INFO - functools32 == 3.2.3-2 15:58:17 INFO - jsonschema == 2.5.1 15:58:17 INFO - manifestparser == 1.1 15:58:17 INFO - mozInstall == 1.12 15:58:17 INFO - mozcrash == 1.0 15:58:17 INFO - mozdebug == 0.1 15:58:17 INFO - mozdevice == 0.49 15:58:17 INFO - mozfile == 1.2 15:58:17 INFO - mozhttpd == 0.7 15:58:17 INFO - mozinfo == 0.9 15:58:17 INFO - mozleak == 0.1 15:58:17 INFO - mozlog == 3.4 15:58:17 INFO - moznetwork == 0.27 15:58:17 INFO - mozprocess == 0.24 15:58:17 INFO - mozprofile == 0.28 15:58:17 INFO - mozrunner == 6.13 15:58:17 INFO - mozscreenshot == 0.1 15:58:17 INFO - mozsystemmonitor == 0.3 15:58:17 INFO - moztest == 0.8 15:58:17 INFO - mozversion == 1.4 15:58:17 INFO - psutil == 3.1.1 15:58:17 INFO - requests == 1.2.3 15:58:17 INFO - wsgiref == 0.1.2 15:58:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:17 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 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:17 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 15:58:17 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 15:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:17 INFO - 'HOME': '/Users/cltbld', 15:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:17 INFO - 'LOGNAME': 'cltbld', 15:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:17 INFO - 'MOZ_NO_REMOTE': '1', 15:58:17 INFO - 'NO_EM_RESTART': '1', 15:58:17 INFO - 'PAGER': '/bin/cat', 15:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:17 INFO - 'PWD': '/builds/slave/test', 15:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:17 INFO - 'SHELL': '/bin/bash', 15:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:17 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:17 INFO - 'USER': 'cltbld', 15:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:17 INFO - 'XPC_FLAGS': '0x0', 15:58:17 INFO - 'XPC_SERVICE_NAME': '0', 15:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 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)) 15:58:17 INFO - Cleaning up... 15:58:17 INFO - Return code: 0 15:58:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa49c8e8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, '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.kOEunjVpEH/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.xEtv5UuiWL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 15:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:17 INFO - 'HOME': '/Users/cltbld', 15:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:17 INFO - 'LOGNAME': 'cltbld', 15:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:17 INFO - 'MOZ_NO_REMOTE': '1', 15:58:17 INFO - 'NO_EM_RESTART': '1', 15:58:17 INFO - 'PAGER': '/bin/cat', 15:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:17 INFO - 'PWD': '/builds/slave/test', 15:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:17 INFO - 'SHELL': '/bin/bash', 15:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:17 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:17 INFO - 'USER': 'cltbld', 15:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:17 INFO - 'XPC_FLAGS': '0x0', 15:58:17 INFO - 'XPC_SERVICE_NAME': '0', 15:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:58:18 INFO - Cleaning up... 15:58:18 INFO - Return code: 0 15:58:18 INFO - Running post-action listener: _resource_record_post_action 15:58:18 INFO - Running post-action listener: _start_resource_monitoring 15:58:18 INFO - Starting resource monitoring. 15:58:18 INFO - [mozharness: 2017-02-10 23:58:18.177901Z] Finished create-virtualenv step (success) 15:58:18 INFO - [mozharness: 2017-02-10 23:58:18.178784Z] Running install step. 15:58:18 INFO - Running pre-action listener: _resource_record_pre_action 15:58:18 INFO - Running main action method: install 15:58:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:18 INFO - Reading from file tmpfile_stdout 15:58:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:58:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:58:18 INFO - Reading from file tmpfile_stdout 15:58:18 INFO - Output received: 15:58:18 INFO - Usage: mozinstall [options] installer 15:58:18 INFO - Options: 15:58:18 INFO - -h, --help show this help message and exit 15:58:18 INFO - -d DEST, --destination=DEST 15:58:18 INFO - Directory to install application into. [default: 15:58:18 INFO - "/builds/slave/test"] 15:58:18 INFO - --app=APP Application being installed. [default: firefox] 15:58:18 INFO - mkdir: /builds/slave/test/build/application 15:58:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.dmg', '--destination', '/builds/slave/test/build/application'] 15:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.dmg --destination /builds/slave/test/build/application 15:58:38 INFO - Reading from file tmpfile_stdout 15:58:38 INFO - Output received: 15:58:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:58:38 INFO - Running post-action listener: _resource_record_post_action 15:58:38 INFO - [mozharness: 2017-02-10 23:58:38.205417Z] Finished install step (success) 15:58:38 INFO - [mozharness: 2017-02-10 23:58:38.205579Z] Running run-tests step. 15:58:38 INFO - Running pre-action listener: _resource_record_pre_action 15:58:38 INFO - Running main action method: run_tests 15:58:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:58:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:58:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:58:38 INFO - 2017-02-10 15:58:38.220 screenresolution[1618:7463] starting screenresolution argv=screenresolution get 15:58:38 INFO - 2017-02-10 15:58:38.223 screenresolution[1618:7463] Display 0: 1600x1200x32@60 15:58:38 INFO - 2017-02-10 15:58:38.229 screenresolution[1619:7465] starting screenresolution argv=screenresolution list 15:58:38 INFO - Available Modes on Display 0 15:58:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:58:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:58:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:58:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:58:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:58:38 INFO - Intel Iris: 15:58:38 INFO - Chipset Model: Intel Iris 15:58:38 INFO - Type: GPU 15:58:38 INFO - Bus: Built-In 15:58:38 INFO - VRAM (Dynamic, Max): 1536 MB 15:58:38 INFO - Vendor: Intel (0x8086) 15:58:38 INFO - Device ID: 0x0a2e 15:58:38 INFO - Revision ID: 0x0009 15:58:38 INFO - Displays: 15:58:38 INFO - Display: 15:58:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:58:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:58:38 INFO - Display Serial Number: 1600x1200 60 15:58:38 INFO - Main Display: Yes 15:58:38 INFO - Mirror: Off 15:58:38 INFO - Online: Yes 15:58:38 INFO - Rotation: Supported 15:58:38 INFO - Return code: 0 15:58:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:58:38 INFO - Python 2.7.3 15:58:38 INFO - Return code: 0 15:58:38 INFO - grabbing minidump binary from tooltool 15:58:38 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')]} 15:58:38 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 0x104becd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104be1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:58:38 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 15:58:38 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 15:58:38 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 15:58:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:58:38 INFO - Return code: 0 15:58:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:58:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:58:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:58:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:58:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:58:38 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/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 15:58:38 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/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0075 --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 15:58:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOEunjVpEH/Render', 15:58:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:38 INFO - 'HOME': '/Users/cltbld', 15:58:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:38 INFO - 'LOGNAME': 'cltbld', 15:58:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:38 INFO - 'MOZ_NO_REMOTE': '1', 15:58:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:38 INFO - 'NO_EM_RESTART': '1', 15:58:38 INFO - 'PAGER': '/bin/cat', 15:58:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:38 INFO - 'PWD': '/builds/slave/test', 15:58:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:38 INFO - 'SHELL': '/bin/bash', 15:58:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEtv5UuiWL/Listeners', 15:58:38 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 15:58:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:38 INFO - 'USER': 'cltbld', 15:58:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:38 INFO - 'XPC_FLAGS': '0x0', 15:58:38 INFO - 'XPC_SERVICE_NAME': '0', 15:58:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:38 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/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 15:58:38 INFO - mozversion application_buildid: 20170210232341 15:58:38 INFO - mozversion application_changeset: 855e6b2f6199189f37cea093cbdd1735e297e8aa 15:58:38 INFO - mozversion application_display_name: Nightly 15:58:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:58:38 INFO - mozversion application_name: Firefox 15:58:38 INFO - mozversion application_remotingname: firefox 15:58:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:58:38 INFO - mozversion application_vendor: Mozilla 15:58:38 INFO - mozversion application_version: 54.0a1 15:58:38 INFO - mozversion platform_buildid: 20170210232341 15:58:38 INFO - mozversion platform_changeset: 855e6b2f6199189f37cea093cbdd1735e297e8aa 15:58:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:58:38 INFO - mozversion platform_version: 54.0a1 15:58:38 INFO - using testdate: 1486771118 15:58:38 INFO - actual date: 1486771118 15:58:38 INFO - starting webserver on 'localhost:49245' 15:58:38 INFO - SUITE-START | Running 2 tests 15:58:38 INFO - TEST-START | dromaeo_css 15:58:38 INFO - operating with platform_type : mac_ 15:58:38 INFO - Initialising browser for dromaeo_css test... 15:58:38 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpQ_Itve/profile http://localhost:49245/getInfo.html) 15:58:41 INFO - PROCESS | 1629 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 15:58:41 INFO - PROCESS | 1629 | 2017-02-10 15:58:41.746 plugin-container[1631:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 15:58:41 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:58:41 INFO - PROCESS | 1629 | 2017-02-10 15:58:41.746 plugin-container[1631:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.CFPasteboardClient' 15:58:41 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:58:41 INFO - PROCESS | 1629 | 2017-02-10 15:58:41.746 plugin-container[1631:7608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:58:41 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 15:58:41 INFO - PROCESS | 1629 | colorDepth:24 15:58:41 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 15:58:41 INFO - PROCESS | 1629 | __metrics 15:58:41 ERROR - PROCESS | 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 15:58:42 INFO - TEST-INFO | 1629: exit 0 15:58:42 INFO - Browser initialized. 15:58:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:58:42 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpQ_Itve/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:45 INFO - PROCESS | 1649 | 2017-02-10 15:58:45.815 plugin-container[1726:8215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 15:58:45 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:58:45 INFO - PROCESS | 1649 | 2017-02-10 15:58:45.816 plugin-container[1726:8215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 15:58:45 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:58:45 INFO - PROCESS | 1649 | 2017-02-10 15:58:45.816 plugin-container[1726:8215] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:58:55 INFO - PROCESS | 1649 | RSS: Main: 229023744 15:58:55 INFO - PROCESS | 1649 | 16:01:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 16:02:01 INFO - PROCESS | 1649 | RSS: Main: 253239296 16:02:01 INFO - PROCESS | 1649 | 16:03:43 INFO - PROCESS | 1649 | ************************* 16:03:43 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 16:03:43 INFO - PROCESS | 1649 | 16:03:43 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:03:43 INFO - PROCESS | 1649 | Full stack: 16:03:43 INFO - PROCESS | 1649 | ************************* 16:05:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 16:05:04 INFO - PROCESS | 1649 | RSS: Main: 256032768 16:05:04 INFO - PROCESS | 1649 | 16:08:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 16:08:06 INFO - PROCESS | 1649 | RSS: Main: 268689408 16:08:06 INFO - PROCESS | 1649 | 16:11:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 16:11:09 INFO - PROCESS | 1649 | RSS: Main: 261812224 16:11:09 INFO - PROCESS | 1649 | 16:14:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 16:14:12 INFO - PROCESS | 1649 | RSS: Main: 266162176 16:14:12 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 16:17:15 INFO - PROCESS | 1649 | RSS: Main: 268980224 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | __start_tp_report 16:17:15 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 16:17:15 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 16:17:15 INFO - PROCESS | 1649 | |i|pagename|runs| 16:17:15 INFO - PROCESS | 1649 | |0;dojo.html;2476.49231907459;2571.9485610287807;2603.8258054524467;2956.6599841018274;2963.8814447422105;3800.9619903800954;3803.915883973068;3827.0623697194155;4081.1225586498895;4122.608352206543;3965.583613720561;4085.8569950051756;4384.846530371437;4995.225740010304;5009.597312752426;3939.7833119178435;3955.6044395560502;4047.6154765297397;4374.846880359188;4468.285074388091;4020.9396859047138;4045.008972801668;4113.856015039474;4682.90634187316;4692.085043416535;3861.69106471483;4181.477315335583;4510.278355463127;4587.747673877935;4680.672352935296;3760.905977350561;3778.5276840394813;3789.6399842014966;3879.806009699532;3906.667933225679;3401.9829900850586;3544.6987006104514;3683.8710645127558;3745.4756334113245;4024.953512086849;3847.076701591598;3878.8751262133947;4103.932977425861;4112.02622741086;4385.56144385562;4269.423627810237;4479.216137175981;4889.33993910821;5330.706811125387;5335.466453354634;4261.616454519077;4288.76411797351;4439.867833702385;4513.187626227278;4610.515895830923;3940.541999460218;4200.411942328076;4403.779811009436;4426.313921342197;4648.233041548175;4025.0339918419954;4398.274284742982;4954.927944674325;5271.920921186109;5670.688112153871;4112.8202052912;4116.423700681907;4142.275101857163;4333.761643109659;5054.49455054499;4063.3701776224734;4277.7647229402055;5236.9526094781195;5538.006317725615;5595.21666966625;4294.012377153273;4596.287575425815;4799.784009719571;5030;5550.389457159709;3761.6238376162164;4175.498940127204;4234.555371685929;4319.33050377192;4449.688521803443;3600.42393217084;3623.963760362363;3834.5973672763957;3941.625545573166;4100.765083029756;3142;3385.9492107618407;3527.735419843522;3607.8376473058775;3818.885433437001;3980.7810570418906;4151.750894946313;4183.330667093251;4255.936160957526;4739.3601863748645;4373;4393.670474714345;4492.989077457546;4553.430821147356;5080.466551012164;3938.9561766131997;3970.642642162219;4044.312466880637;4087.9355925257555;4262.978685106555;3837.424386342071;3949.0522274653877;4027.8791636250958;4153.400940637815;4235.174141042528;4596.943032883095;4690;4747.006257371843;5720.914186287126;5813.476787089182;4061;4252.491030292143;4790.520947905321;5285.656432331886;5613.46672066153;4202.2646036944025;4389.078293558261;4634.791434385528;4786.425628924551;4788.61691064721;4333.003409215835;4403.3174857897075;5475.863103422446;5564.554835613222;5587.525060369914;3831.482749828849;4395.318725597537;4598.896264896334;4637.628989680885;5280.498352656491;4120.75275483472;4308.870733877989;4463.4420697412825;5139.331886854685;5151.948480515297;4120.835166593302;4451.5754958413;4557.384753058294;4632.652551058617;5206.167013277857;4537.205988951986;4577.779776808312;4603.93094103582;4617.799372163195;4816.783244754065;3911.8630847920185;4154.3145381013;4364.956350436582;4411.536788637147;4448.354988526581;3854.4989151410136;4167.5832416759295;4253.000544872015;4559.065391594781;4860.0279944012045 16:17:15 INFO - PROCESS | 1649 | |1;ext.html;391.6318660459168;459.51980180711166;466.5357968821024;485.1558288577876;485.15825043549455;33313.83343083284;38206.61793382066;38244.80877595615;38246.617533824654;38353.23293534128;39013;39782.60217397825;39868.00329991752;39885.210904963984;39949.40075898864;8933.062028486996;9234.6306147754;9315.975242723294;10377.740556486111;10753.731156721105;10518.105960993325;10592.78814423711;10597.311174773653;10606.666533362686;10629;9044.954775226115;10651.147908167328;10655.84016239757;10664.360138391721;10674.252802303803;10575.78848423035;10610.991955764151;10623.097036751886;10627.41549214793;10640.94679526605;8834.514101724402;9039.864402033974;10575.629852955186;10622.203334749925;10641.829398766129;34929;34936.12659683503;35049.474257886155;35074.597016119325;35172.82413587916;3570.393033184365;3621.692156166729;3634.727395445352;3645.2891686121216;3762.868299609528;26428.471430571473;27247.046353377536;27349.589756153673;27444.588331175048;27467.587986180224;3066.877324907001;3077.569140320357;3121.469350210447;3193.5209718542174;3201.4557525220766;431287;435649;437993.810030951;440145;441281;44620.10759784851;45882.54117458783;51747.74126129345;63087.6845615769;63838;50119.498805011484;63025.68487157535;66878.3312166872;67354;67463.98804017944;14991.250437478082;15112.848871511364;15119.319630616648;15142.54572362831;15148.863835212404;11398.601048963246;11482.48328825205;11517.942410287895;11554.017908477705;11594.76810463786;19000.80999190018;19537;19825.504362391057;19913.800861991484;19955.102020409115;6888.758893438706;8216.671333146727;8370.455920365279;8396.244338009608;8414.579271036424;818.2799136759613;832.6253186066294;832.9541875196801;850.5534594338022;859.1151114352227;28741.56887646687;29337.853310733735;29367.11898643044;29761.851190743906;29880.804767809044;992.5483904823288;1004.1464754958228;1004.2719028704131;1038.2732087538752;1047.2250534604339;14513.201773902325;14983.700325993419;15035.774463383059;15362.846371536141;15368.077915325117;605354;609903;609992;610340;610660;56219;56462.717686411306;56470.152947705814;56714.149287760716;56801;1268.2137075013366;1325.4234408032537;1335.1388354511091;1335.5859683498147;1344.536135033412;24120.777647595827;28641.570376443953;29122.417551648425;29170.708292917647;29281.560776588794;47208.76395618;47304;47596;47869.5213047865;47934.76032619815;496100;497591;497792;499211.503942478;499248;23978;24261.150859719826;24618.876905615358;24670.383240419134;39953.80023099866;32396.190095247806;39120.80439597784;39499.60500394959;39761;40577;32510;33538.993830185136;33620.49569256512;34466.655333446346;34676.306473869874;33109;33290.66709332875;34171.82914085413;34197;34539.4819077709;32914.67085329212;33960.66039339674;34065.14837129092;34175.829120854236;34321.97034088981;27194.728052720013;28824.423511530073;29423;29550.556741648463;29571;21878.418815160025;21956.341309760734;22078.22726204575;22145.667814982466;22252.109915603196 16:17:15 INFO - PROCESS | 1649 | |2;jquery.html;343.76452130290767;377.41312259436575;378.5381834162321;387.9854181315859;414.05595242846323;18748.62502749944;19737.21051157952;19911.30310439135;20383;20515.897420512913;18699.62600747984;18825.05874706266;19193.32823351183;19297.4210773677;19298.421047368567;4089.3865920111925;4898;5140.490538898411;5202.713850738208;5266.92099618506;4460.955390446103;5274.709890955995;5280.735963201843;5285.312909321783;5285.4450282720145;5210.791568337262;5271.4464981177;5287.867803304929;5288.788448462057;5296.496832800878;5627.127795191752;5707.828765137032;5730.828075157752;5742.827715168552;5751.827445176651;4405.559444055569;4924.285978533093;5135.897282054375;5138.7173705446185;5149.176131818891;12903.35483225835;13029.934850325782;13053.955683545388;13064.346782660925;14898.851011489964;2465.790407815338;2473.2703852363597;2486.6021436570245;2488.925332240036;2499.4376265340334;9598.568064437048;9667.516624168764;10183.694489165337;10381.532831022621;14956.40174393023;1714.554215903874;1742.4337090445656;1748.2744660965811;1778.2264714849082;1793.3364655077705;94013.70952016715;127291.3635431817;128771.35614321868;129109.35445322713;129243;11548.307101573933;11846.940765296118;11876.88123118775;11878.88121118795;11923.880761192451;15419.922900385427;15429.768553471707;15454.304556294994;15460.613484662748;15501.689966200838;6637.734490620418;8197.79505512367;8280.627371768285;8334.541600211925;8373.832523349622;7779.338756205672;7786.338161256243;7795.610219489116;7946.880796788053;7958.641861116148;4445.3998710174355;4475;4705.247160454379;4787.3776409067295;4892.290617860462;2004.6892731626622;2007.5181956330375;2073.4919944613666;2081.4484161697164;2083.91664333428;938.9765255868522;967.777411195415;972.2824704949683;987.7975015121883;1004.6282875336173;19833.710808797383;20925.686114708293;21033.26383576598;21072.578548428944;21158.788412115682;893.9687110951086;984.5471083301618;991.1278075293695;1027.5376080763685;1028.4960369418932;8434.849113476024;10645.190317646018;10760.838587421194;10909.39998300101;10975.83536246957;297658.5351219734;330887;362086;672340;674234;118675;147234;149961;150026.7495987561;153459;944.6127087893931;950.9239260859115;974.770928831724;976.023976023976;980.0444566547701;17236.39672611503;18261.178246978652;18325.725114123565;18329.81670183281;18465.90767046156;58482.707586461795;59214;59664.50838729066;61015;61114;84945.57527212324;85141;85295.14704852871;85849.57075214584;86192.13807861842;7469.514481558681;7472.626368681435;8122.959385203036;8251.876221856557;8465.873011904949;10630.52162652698;12576.74846503083;12604.621861344174;12862.935685321514;12952.611421657366;10450.111740502143;10648.307859989076;11122.833157502806;11291.983721465107;11296.548138074386;8924;9314.813703725751;10697.786044278915;10917.39954302521;11099.389533575732;10823.242373033812;11266.267692599955;11315.660530184108;11444.771104577696;11464.713382165513;7015.438764898898;7923.128455869734;8133.8373232536205;8235.464694794819;8352.707655232038;8354.87467687973;11313.943430282796;11321.377324247245;11360.602378916697;11364.54541818351 16:17:15 INFO - PROCESS | 1649 | |3;mootools.html;1385.092764239423;1413.109740863256;1416.992915035424;1431.976137062001;1437.1018113678952;8130.390220733447;8147.981502312211;8447.662093516252;8616.138386161372;8655.78360540986;8383.329333653293;8513.148685131484;8518.488890666564;8524.786880327994;8558.315334773219;2232.620454522735;2277;2284.383216531544;2287.421679041456;2290.438842483594;2261.6720575516542;2301.436148143707;2303.9769602304013;2304.3893368257427;2315.9305220843316;2244.2145249162827;2253.3465295064493;2273.9772602274015;2279.133929106937;2280.680704701343;2166.9349919502442;2232.821374290053;2248.471039693008;2253.43664083979;2262.7171603549473;1974.1511150205406;2012.466696325475;2054.7739748627637;2238.955220895573;2284.474570848714;7823.843523129505;8451.746447606582;8522.786930326729;8576.05663377028;8585.527795971218;4489.595936365743;5081.441041485434;5159.458256883011;5228.8692782680355;5255.86860328491;3421.555197824292;3477.539433437962;3479.3389256041273;3529.517602606911;3557.288542291552;1158.9652310430702;1160.2052593973071;1163.96508104757;1169.0297053445704;1194;10993.340399576051;11012.834807477895;11058.889411105867;11068.225224234226;11084.61203857861;4256.2551553478015;4423.225935461282;4475.977620111878;4507.81968721254;4508.233600287894;8759.211670949519;8802.987656419593;8814.823703525894;8815.382923195315;8852.937647482344;5074.2895994560795;5123.001014802152;5131.897362052738;5138.614603904722;5142.357205349332;4644.814207431665;4651.651126165551;4719.480857105716;4726.038693942044;4793.209120495079;3596.536372584788;3606.1886075633297;3667;3685.0787303174206;3700.4634328022285;976.7655762616921;1128.5711429656678;1132.371533798733;1143.0512674480162;1150.9769804603861;764.2166779051406;764.8546776112632;785.9389823738022;787.6967367563415;802.1377019703725;5345.893082138336;5463.972680136574;5514.558835293167;5589.944100559023;5595.832125036255;446.6002927379977;530.254991736603;549.181719238332;554.6644280210495;557.9009351577386;3624.130208749881;3655.853765849389;3747.718921080875;3770.472133901256;3774.6602805747616;9098.681546145854;9460.8580871287;9570.95214523937;9807.215422766161;9851.802963940825;9126.041765614647;10095.394276343442;10139.594416223415;10142.29003969715;10160.796784064278;7481.177070522238;7911.604419778988;7933.167017463199;7939.64271607779;7971.44199906001;4428.778561071869;5466.535344495763;5590.329160500766;5661.830145095653;5755.050416681201;9112.271018318386;9131.954340228256;9202.217811486098;9292.860607091034;9363.250939924927;9235.26117910579;9470.479123648265;9491.240700743785;9537.52168413897;9537.570809313458;6698.631575263407;6776.424003959719;6781.6270105144695;6789.592624442352;6792.93207067923;6716.496262780213;7798.805029874298;7852.646630901738;7893.565853878092;7909.644066016928;5998.040313549811;7143.678534465857;7180.856382872418;7182.784516464514;7228.566286022856;7005.334493223136;7078;7088.964555177191;7158.248383919614;7158.606276654834;6283.465905398092;6323.683815809283;6328.24061112651;6402.455791257609;6417.550771445954;4836.782344794422;4879.756012199305;4929.112759703288;4964.67729597575;5148.613853960893;4406.4051353066925;4903.803847846046;4962.627802914723;5010.248462730619;5084.3644544431945 16:17:15 INFO - PROCESS | 1649 | |4;prototype.html;327.5185477348298;334.60944189113394;347.65234765234766;353.4556782554865;354.67724370822526;4104.794760261982;4629.537046295369;5351.625386222966;5454.536364409025;5470.2615146955195;4631.560001799832;4694.8028252795575;4972.651914365996;5131.202909846715;5286.682799032061;3707.3141468828217;4201.941048886054;4209.621134097931;4217.261979153651;4238.682098842583;3800.2969450651717;3969.729686500321;4230.863413975938;4246.851360202393;4247.723897946639;4094.651954583849;4138.2758017347;4152.813123409439;4154.584541545836;4158.355454904495;3672.6143754905615;4005.2790497710403;4137.193247316774;4143.461350024492;4169.708120431571;3622.768258791997;3683.5395575553057;3693.098054501934;3694.704423646129;3711.981440092782;4233.111046680201;4883.92553638199;5127.256547800548;5146.716930568818;5228.686278823283;4527.886802829923;4566.433566433567;4576.496585375606;4588;4595.2188128018315;4286.906838756128;4441.511433742286;4771.33201351811;5614.6631202128;5681.289838770153;3682.0853156358544;3732.3654978653694;3769.8869033929027;3826.617338266179;3839.4432807242792;4916.852494425173;5472.589555783293;5600.635958662715;5800.941990580082;5941.881162376728;3109.8445077746023;3187.6493585705553;3826.7512611680154;3860.864869729546;3871.032241939515;3336.349411864663;3336.4828451590042;3466.5666791651042;3494.336076145524;3968.7817170055346;4104.8768536943935;4422.115576884636;4595;4605.016660588567;4644.164050470948;4462.397576327219;4980.904201075758;4993.90012199754;5585.3297604287745;5613.153620827528;1229.4528934624186;1236.3076677060874;1276.2897717059186;1277.4187744576195;1281.3785314122642;730.5689643110591;850.7961234852738;862.2843040276556;869.8995770350512;870.777951622345;4296.4414626098405;4828.782704778293;4894.583960363338;5045.974770126126;5053.31780209667;4996.325496057985;5086.830029093329;5340.652857564246;5527.50252477279;5534.169874518854;4226.992178715086;4428.867133985921;4732.503087175797;5028.874278143076;5049;2505.523950449409;2649.7217792131933;2894.609227754226;2934.7358737713707;2992.772963085168;5459.972700136554;5823.505002074871;5823.941760582425;5851.741875496788;5896.233489646319;4790.473047964721;5453.781848726086;5773;5773.393793651606;5910.734016969247;6040.244969378916;6040.577159598786;6044.788432404845;6064.302605200459;6073.99779036454;5559.360673522435;5848.239728835224;5850.473457388855;5853.35613082552;5893.292804863444;5707.001274776981;5747.453991870766;5782.45066718661;5786.855328616818;5802.94197058024;5596.460973232393;5727.0550359190265;5972.283326000907;6032.758689652364;6132.233470816148;3799.4870692456166;3867.5358956925347;3924.2543916655745;3926.9214615706956;3934.1738234970976;5776.389756402248;5777.884442311214;5833.562482813891;5846.35690074083;5850.829834033296;4759.476457589593;5321.472300657157;5658.405867383865;5695.800646977336;5714.485696287354;4749.833755818529;5050.747462626928;5095.13382724945;5686.09022556389;5716.971415142898;5591.832245032493;5597.972010139923;5616.494515493625;5648.774049037993;5653.491185793298;4295.162443323522;4440.823996241883;5374.641195684512;5469.753861076181;5718.656880587178;5566.137248726453;5580.0748741685675;5600.579956503197;5639.915401269066;5644.3508996465935 16:17:15 INFO - PROCESS | 1649 | |5;yui.html;661.646019379632;685.8045457044743;747.0587060304015;766.5592284436449;769.6651956398966;11605.593804216855;14906.403743850233;14975;15001.249937503137;15028.549143525684;13475.326233688302;13624.659383515416;13651.044426890121;13657.726845463085;13664.248466334348;686.6120641837346;711.5197241861748;843.1905370843982;873.9825203495926;876.7545087375545;873.6199753107387;880.1286726141107;880.7930136417937;880.7974165941837;885.995570022149;921.410297409655;924.4915296586851;925.6852670092168;926.1664501948233;929.8419268724333;821.9314890642171;844.8563744163507;920.6271460058687;921.2169655792557;924.8381533231658;683.4907993544841;683.9350261725128;684.3020119478155;685.9211190713082;688.1948120698794;12544.498220071277;14164.645883852883;14663.560093197115;15133.167675777828;15145;13895.166290022631;14198.645033874236;14217.431302747878;14265.857341426557;14277.214753188571;11020.834687479693;11084.722881927935;11108;11158.944205279004;11493;2980.7019298070236;3619.6199399062984;3642.7996460194677;3651.2697460508007;3764.9058773530605;95853.52073239659;97807.0219297805;98120.03759924833;98306;99885.50057249668;10609.681709548726;10623.043926046694;10771.83842242367;10957.726056848483;11483.827742583868;20416.591668166555;22372.32883013512;22949.311520654406;23337.649935250985;23529.647055294183;6548.705308261045;7418.6646403647865;7611.238876112455;7689.346405555479;7821.178776228414;3980.1840622672285;4097.221527909687;4097.508299004079;4099.262132816062;4102.815373308208;3538.5399898013093;3549.0594992327055;3598.8020658864016;3632.2372301816386;3634.854605815791;656.9474442044625;662.2020465339255;666.190578447189;674.5008693566823;675.8242856857253;516.6744950681046;578.254052272572;589.7316720891984;591.4942723970943;595.2886300870418;8006;9216.493092879822;9832.508374581384;9845;9987.250956178315;606.9423404776539;656.228931006066;660.2341284110505;667.3993405934698;692.375414704024;4193.91921354104;4585.358049873021;4668.85993420198;4717.631886752872;4735.526447355306;65939.01091483631;72089.27910720826;73832;89226.43094276157;97155.51422242844;69119;69917;70349.64825175841;71932.64033679903;73127.53744925071;51953.70115747137;58789;64811;65686.34313656802;66465.00604981858;9795.9020409795;10331.276810623485;10482.213833962636;10496.895031049591;10519.368837869752;45463.54536454725;46245.76877115727;46706.53293467022;46919.29621055755;47257;49150;54815;58159.4184058154;58507;59483.70258148855;11399.259048161843;11732.94133529327;11793.823092653445;11849.289042657469;11876.82184767212;13518.59444216675;13523.323833808467;13567;13573.195323287177;13804.240766757523;12565.057620678303;12615.81076283838;12907.354632268536;12988.545400910923;13121.606351809462;12464.813027804408;12609.810852837032;12630.557930472389;12863;13016.414261358452;11759.764804703687;11796.587119450778;11943.283402995849;12109.273443593413;12317.137800353888;10012.39925604466;10017.949910250401;10037.397756134655;10338.741531461774;10419.895801042196;8210.973628296462;8665;9404.670836520689;10279.359509968546;10399.480025998519 16:17:15 INFO - PROCESS | 1649 | __end_tp_report 16:17:15 INFO - PROCESS | 1649 | __start_cc_report 16:17:15 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,417 16:17:15 INFO - PROCESS | 1649 | __end_cc_report 16:17:15 INFO - PROCESS | 1649 | __startTimestamp1486772235924__endTimestamp 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | ------- Summary: start ------- 16:17:15 INFO - PROCESS | 1649 | Number of tests: 6 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:4337.97 Median:4288.76 stddev:596.88 (13.9%) stddev-sans-first:580.57 16:17:15 INFO - PROCESS | 1649 | Values: 2476.5 2571.9 2603.8 2956.7 2963.9 3801.0 3803.9 3827.1 4081.1 4122.6 3965.6 4085.9 4384.8 4995.2 5009.6 3939.8 3955.6 4047.6 4374.8 4468.3 4020.9 4045.0 4113.9 4682.9 4692.1 3861.7 4181.5 4510.3 4587.7 4680.7 3760.9 3778.5 3789.6 3879.8 3906.7 3402.0 3544.7 3683.9 3745.5 4025.0 3847.1 3878.9 4103.9 4112.0 4385.6 4269.4 4479.2 4889.3 5330.7 5335.5 4261.6 4288.8 4439.9 4513.2 4610.5 3940.5 4200.4 4403.8 4426.3 4648.2 4025.0 4398.3 4954.9 5271.9 5670.7 4112.8 4116.4 4142.3 4333.8 5054.5 4063.4 4277.8 5237.0 5538.0 5595.2 4294.0 4596.3 4799.8 5030.0 5550.4 3761.6 4175.5 4234.6 4319.3 4449.7 3600.4 3624.0 3834.6 3941.6 4100.8 3142.0 3385.9 3527.7 3607.8 3818.9 3980.8 4151.8 4183.3 4255.9 4739.4 4373.0 4393.7 4493.0 4553.4 5080.5 3939.0 3970.6 4044.3 4087.9 4263.0 3837.4 3949.1 4027.9 4153.4 4235.2 4596.9 4690.0 4747.0 5720.9 5813.5 4061.0 4252.5 4790.5 5285.7 5613.5 4202.3 4389.1 4634.8 4786.4 4788.6 4333.0 4403.3 5475.9 5564.6 5587.5 3831.5 4395.3 4598.9 4637.6 5280.5 4120.8 4308.9 4463.4 5139.3 5151.9 4120.8 4451.6 4557.4 4632.7 5206.2 4537.2 4577.8 4603.9 4617.8 4816.8 3911.9 4154.3 4365.0 4411.5 4448.4 3854.5 4167.6 4253.0 4559.1 4860.0 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:64204.24 Median:34012.90 stddev:138794.86 (408.1%) stddev-sans-first:139101.52 16:17:15 INFO - PROCESS | 1649 | Values: 391.6 459.5 466.5 485.2 485.2 33313.8 38206.6 38244.8 38246.6 38353.2 39013.0 39782.6 39868.0 39885.2 39949.4 8933.1 9234.6 9316.0 10377.7 10753.7 10518.1 10592.8 10597.3 10606.7 10629.0 9045.0 10651.1 10655.8 10664.4 10674.3 10575.8 10611.0 10623.1 10627.4 10640.9 8834.5 9039.9 10575.6 10622.2 10641.8 34929.0 34936.1 35049.5 35074.6 35172.8 3570.4 3621.7 3634.7 3645.3 3762.9 26428.5 27247.0 27349.6 27444.6 27467.6 3066.9 3077.6 3121.5 3193.5 3201.5 431287.0 435649.0 437993.8 440145.0 441281.0 44620.1 45882.5 51747.7 63087.7 63838.0 50119.5 63025.7 66878.3 67354.0 67464.0 14991.3 15112.8 15119.3 15142.5 15148.9 11398.6 11482.5 11517.9 11554.0 11594.8 19000.8 19537.0 19825.5 19913.8 19955.1 6888.8 8216.7 8370.5 8396.2 8414.6 818.3 832.6 833.0 850.6 859.1 28741.6 29337.9 29367.1 29761.9 29880.8 992.5 1004.1 1004.3 1038.3 1047.2 14513.2 14983.7 15035.8 15362.8 15368.1 605354.0 609903.0 609992.0 610340.0 610660.0 56219.0 56462.7 56470.2 56714.1 56801.0 1268.2 1325.4 1335.1 1335.6 1344.5 24120.8 28641.6 29122.4 29170.7 29281.6 47208.8 47304.0 47596.0 47869.5 47934.8 496100.0 497591.0 497792.0 499211.5 499248.0 23978.0 24261.2 24618.9 24670.4 39953.8 32396.2 39120.8 39499.6 39761.0 40577.0 32510.0 33539.0 33620.5 34466.7 34676.3 33109.0 33290.7 34171.8 34197.0 34539.5 32914.7 33960.7 34065.1 34175.8 34322.0 27194.7 28824.4 29423.0 29550.6 29571.0 21878.4 21956.3 22078.2 22145.7 22252.1 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:31822.47 Median:150078.99 stddev:85355.71 (56.9%) stddev-sans-first:85562.43 16:17:15 INFO - PROCESS | 1649 | Values: 343.8 377.4 378.5 388.0 414.1 18748.6 19737.2 19911.3 20383.0 20515.9 18699.6 18825.1 19193.3 19297.4 19298.4 4089.4 4898.0 5140.5 5202.7 5266.9 4461.0 5274.7 5280.7 5285.3 5285.4 5210.8 5271.4 5287.9 5288.8 5296.5 5627.1 5707.8 5730.8 5742.8 5751.8 4405.6 4924.3 5135.9 5138.7 5149.2 12903.4 13029.9 13054.0 13064.3 14898.9 2465.8 2473.3 2486.6 2488.9 2499.4 9598.6 9667.5 10183.7 10381.5 14956.4 1714.6 1742.4 1748.3 1778.2 1793.3 94013.7 127291.4 128771.4 129109.4 129243.0 11548.3 11846.9 11876.9 11878.9 11923.9 15419.9 15429.8 15454.3 15460.6 15501.7 6637.7 8197.8 8280.6 8334.5 8373.8 7779.3 7786.3 7795.6 7946.9 7958.6 4445.4 4475.0 4705.2 4787.4 4892.3 2004.7 2007.5 2073.5 2081.4 2083.9 939.0 967.8 972.3 987.8 1004.6 19833.7 20925.7 21033.3 21072.6 21158.8 894.0 984.5 991.1 1027.5 1028.5 8434.8 10645.2 10760.8 10909.4 10975.8 297658.5 330887.0 362086.0 672340.0 674234.0 118675.0 147234.0 149961.0 150026.7 153459.0 944.6 950.9 974.8 976.0 980.0 17236.4 18261.2 18325.7 18329.8 18465.9 58482.7 59214.0 59664.5 61015.0 61114.0 84945.6 85141.0 85295.1 85849.6 86192.1 7469.5 7472.6 8123.0 8251.9 8465.9 10630.5 12576.7 12604.6 12862.9 12952.6 10450.1 10648.3 11122.8 11292.0 11296.5 8924.0 9314.8 10697.8 10917.4 11099.4 10823.2 11266.3 11315.7 11444.8 11464.7 7015.4 7923.1 8133.8 8235.5 8352.7 8354.9 11313.9 11321.4 11360.6 11364.5 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5361.46 Median:5145.49 stddev:2985.81 (58.0%) stddev-sans-first:2979.23 16:17:15 INFO - PROCESS | 1649 | Values: 1385.1 1413.1 1417.0 1432.0 1437.1 8130.4 8148.0 8447.7 8616.1 8655.8 8383.3 8513.1 8518.5 8524.8 8558.3 2232.6 2277.0 2284.4 2287.4 2290.4 2261.7 2301.4 2304.0 2304.4 2315.9 2244.2 2253.3 2274.0 2279.1 2280.7 2166.9 2232.8 2248.5 2253.4 2262.7 1974.2 2012.5 2054.8 2239.0 2284.5 7823.8 8451.7 8522.8 8576.1 8585.5 4489.6 5081.4 5159.5 5228.9 5255.9 3421.6 3477.5 3479.3 3529.5 3557.3 1159.0 1160.2 1164.0 1169.0 1194.0 10993.3 11012.8 11058.9 11068.2 11084.6 4256.3 4423.2 4476.0 4507.8 4508.2 8759.2 8803.0 8814.8 8815.4 8852.9 5074.3 5123.0 5131.9 5138.6 5142.4 4644.8 4651.7 4719.5 4726.0 4793.2 3596.5 3606.2 3667.0 3685.1 3700.5 976.8 1128.6 1132.4 1143.1 1151.0 764.2 764.9 785.9 787.7 802.1 5345.9 5464.0 5514.6 5589.9 5595.8 446.6 530.3 549.2 554.7 557.9 3624.1 3655.9 3747.7 3770.5 3774.7 9098.7 9460.9 9571.0 9807.2 9851.8 9126.0 10095.4 10139.6 10142.3 10160.8 7481.2 7911.6 7933.2 7939.6 7971.4 4428.8 5466.5 5590.3 5661.8 5755.1 9112.3 9132.0 9202.2 9292.9 9363.3 9235.3 9470.5 9491.2 9537.5 9537.6 6698.6 6776.4 6781.6 6789.6 6792.9 6716.5 7798.8 7852.6 7893.6 7909.6 5998.0 7143.7 7180.9 7182.8 7228.6 7005.3 7078.0 7089.0 7158.2 7158.6 6283.5 6323.7 6328.2 6402.5 6417.6 4836.8 4879.8 4929.1 4964.7 5148.6 4406.4 4903.8 4962.6 5010.2 5084.4 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4500.77 Median:4905.72 stddev:1400.17 (28.5%) stddev-sans-first:1368.61 16:17:15 INFO - PROCESS | 1649 | Values: 327.5 334.6 347.7 353.5 354.7 4104.8 4629.5 5351.6 5454.5 5470.3 4631.6 4694.8 4972.7 5131.2 5286.7 3707.3 4201.9 4209.6 4217.3 4238.7 3800.3 3969.7 4230.9 4246.9 4247.7 4094.7 4138.3 4152.8 4154.6 4158.4 3672.6 4005.3 4137.2 4143.5 4169.7 3622.8 3683.5 3693.1 3694.7 3712.0 4233.1 4883.9 5127.3 5146.7 5228.7 4527.9 4566.4 4576.5 4588.0 4595.2 4286.9 4441.5 4771.3 5614.7 5681.3 3682.1 3732.4 3769.9 3826.6 3839.4 4916.9 5472.6 5600.6 5800.9 5941.9 3109.8 3187.6 3826.8 3860.9 3871.0 3336.3 3336.5 3466.6 3494.3 3968.8 4104.9 4422.1 4595.0 4605.0 4644.2 4462.4 4980.9 4993.9 5585.3 5613.2 1229.5 1236.3 1276.3 1277.4 1281.4 730.6 850.8 862.3 869.9 870.8 4296.4 4828.8 4894.6 5046.0 5053.3 4996.3 5086.8 5340.7 5527.5 5534.2 4227.0 4428.9 4732.5 5028.9 5049.0 2505.5 2649.7 2894.6 2934.7 2992.8 5460.0 5823.5 5823.9 5851.7 5896.2 4790.5 5453.8 5773.0 5773.4 5910.7 6040.2 6040.6 6044.8 6064.3 6074.0 5559.4 5848.2 5850.5 5853.4 5893.3 5707.0 5747.5 5782.5 5786.9 5802.9 5596.5 5727.1 5972.3 6032.8 6132.2 3799.5 3867.5 3924.3 3926.9 3934.2 5776.4 5777.9 5833.6 5846.4 5850.8 4759.5 5321.5 5658.4 5695.8 5714.5 4749.8 5050.7 5095.1 5686.1 5717.0 5591.8 5598.0 5616.5 5648.8 5653.5 4295.2 4440.8 5374.6 5469.8 5718.7 5566.1 5580.1 5600.6 5639.9 5644.4 16:17:15 INFO - PROCESS | 1649 | 16:17:15 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:17937.29 Median:25687.70 stddev:24516.57 (95.4%) stddev-sans-first:24551.03 16:17:15 INFO - PROCESS | 1649 | Values: 661.6 685.8 747.1 766.6 769.7 11605.6 14906.4 14975.0 15001.2 15028.5 13475.3 13624.7 13651.0 13657.7 13664.2 686.6 711.5 843.2 874.0 876.8 873.6 880.1 880.8 880.8 886.0 921.4 924.5 925.7 926.2 929.8 821.9 844.9 920.6 921.2 924.8 683.5 683.9 684.3 685.9 688.2 12544.5 14164.6 14663.6 15133.2 15145.0 13895.2 14198.6 14217.4 14265.9 14277.2 11020.8 11084.7 11108.0 11158.9 11493.0 2980.7 3619.6 3642.8 3651.3 3764.9 95853.5 97807.0 98120.0 98306.0 99885.5 10609.7 10623.0 10771.8 10957.7 11483.8 20416.6 22372.3 22949.3 23337.6 23529.6 6548.7 7418.7 7611.2 7689.3 7821.2 3980.2 4097.2 4097.5 4099.3 4102.8 3538.5 3549.1 3598.8 3632.2 3634.9 656.9 662.2 666.2 674.5 675.8 516.7 578.3 589.7 591.5 595.3 8006.0 9216.5 9832.5 9845.0 9987.3 606.9 656.2 660.2 667.4 692.4 4193.9 4585.4 4668.9 4717.6 4735.5 65939.0 72089.3 73832.0 89226.4 97155.5 69119.0 69917.0 70349.6 71932.6 73127.5 51953.7 58789.0 64811.0 65686.3 66465.0 9795.9 10331.3 10482.2 10496.9 10519.4 45463.5 46245.8 46706.5 46919.3 47257.0 49150.0 54815.0 58159.4 58507.0 59483.7 11399.3 11732.9 11793.8 11849.3 11876.8 13518.6 13523.3 13567.0 13573.2 13804.2 12565.1 12615.8 12907.4 12988.5 13121.6 12464.8 12609.8 12630.6 12863.0 13016.4 11759.8 11796.6 11943.3 12109.3 12317.1 10012.4 10017.9 10037.4 10338.7 10419.9 8211.0 8665.0 9404.7 10279.4 10399.5 16:17:15 INFO - PROCESS | 1649 | -------- Summary: end -------- 16:17:15 INFO - PROCESS | 1649 | 16:17:16 INFO - TEST-INFO | 1649: exit 0 16:17:16 INFO - TEST-OK | dromaeo_css | took 1117871ms 16:17:16 INFO - TEST-START | kraken 16:17:16 INFO - operating with platform_type : mac_ 16:17:16 INFO - Initialising browser for kraken test... 16:17:16 INFO - TEST-INFO | started process 9603 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpaKbTUT/profile http://localhost:49245/getInfo.html) 16:17:19 INFO - PROCESS | 9603 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 16:17:19 INFO - PROCESS | 9603 | 2017-02-10 16:17:19.820 plugin-container[9604:51706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 16:17:19 INFO - PROCESS | 9603 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:19 INFO - PROCESS | 9603 | 2017-02-10 16:17:19.821 plugin-container[9604:51706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:17:19 INFO - PROCESS | 9603 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:19 INFO - PROCESS | 9603 | 2017-02-10 16:17:19.821 plugin-container[9604:51706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:17:19 INFO - PROCESS | 9603 | __metrics Screen width/height:1600/1200 16:17:19 INFO - PROCESS | 9603 | colorDepth:24 16:17:19 INFO - PROCESS | 9603 | Browser inner width/height: 1024/689 16:17:19 INFO - PROCESS | 9603 | __metrics 16:17:19 ERROR - PROCESS | 9603 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 16:17:20 INFO - TEST-INFO | 9603: exit 0 16:17:20 INFO - Browser initialized. 16:17:20 INFO - Running cycle 1/1 for kraken test... 16:17:20 INFO - TEST-INFO | started process 9645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpaKbTUT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:24 INFO - PROCESS | 9645 | 2017-02-10 16:17:24.203 plugin-container[9734:52499] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 16:17:24 INFO - PROCESS | 9645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:24 INFO - PROCESS | 9645 | 2017-02-10 16:17:24.204 plugin-container[9734:52499] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 16:17:24 INFO - PROCESS | 9645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:24 INFO - PROCESS | 9645 | 2017-02-10 16:17:24.204 plugin-container[9734:52499] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:17:33 INFO - PROCESS | 9645 | RSS: Main: 229388288 16:17:33 INFO - PROCESS | 9645 | 16:17:37 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 16:17:38 INFO - PROCESS | 9645 | RSS: Main: 243556352 16:17:38 INFO - PROCESS | 9645 | 16:17:41 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 16:17:42 INFO - PROCESS | 9645 | RSS: Main: 250789888 16:17:42 INFO - PROCESS | 9645 | 16:17:46 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 16:17:47 INFO - PROCESS | 9645 | RSS: Main: 255029248 16:17:47 INFO - PROCESS | 9645 | 16:17:49 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 16:17:50 INFO - PROCESS | 9645 | RSS: Main: 255299584 16:17:50 INFO - PROCESS | 9645 | 16:17:52 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:17:53 INFO - PROCESS | 9645 | RSS: Main: 261017600 16:17:53 INFO - PROCESS | 9645 | 16:17:56 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 16:17:57 INFO - PROCESS | 9645 | RSS: Main: 259407872 16:17:57 INFO - PROCESS | 9645 | 16:18:00 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 16:18:01 INFO - PROCESS | 9645 | RSS: Main: 260612096 16:18:01 INFO - PROCESS | 9645 | 16:18:04 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 16:18:05 INFO - PROCESS | 9645 | RSS: Main: 258973696 16:18:05 INFO - PROCESS | 9645 | 16:18:07 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:18:08 INFO - PROCESS | 9645 | RSS: Main: 259874816 16:18:08 INFO - PROCESS | 9645 | 16:18:10 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:18:11 INFO - PROCESS | 9645 | RSS: Main: 260423680 16:18:11 INFO - PROCESS | 9645 | 16:18:13 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:18:14 INFO - PROCESS | 9645 | RSS: Main: 261009408 16:18:14 INFO - PROCESS | 9645 | 16:18:18 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:18:18 INFO - PROCESS | 9645 | RSS: Main: 260509696 16:18:18 INFO - PROCESS | 9645 | 16:18:22 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:18:23 INFO - PROCESS | 9645 | RSS: Main: 261890048 16:18:23 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 16:18:25 INFO - PROCESS | 9645 | RSS: Main: 264224768 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | __start_tp_report 16:18:25 INFO - PROCESS | 9645 | _x_x_mozilla_page_load 16:18:25 INFO - PROCESS | 9645 | _x_x_mozilla_page_load_details 16:18:25 INFO - PROCESS | 9645 | |i|pagename|runs| 16:18:25 INFO - PROCESS | 9645 | |0;ai-astar;118;127;154;187;140;175;135;177;157;143 16:18:25 INFO - PROCESS | 9645 | |1;audio-beat-detection;158;122;160;177;156;139;134;131;203;157 16:18:25 INFO - PROCESS | 9645 | |2;audio-dft;258;269;239;260;274;294;256;294;254;258 16:18:25 INFO - PROCESS | 9645 | |3;audio-fft;112;67;87;97;83;75;91;90;93;81 16:18:25 INFO - PROCESS | 9645 | |4;audio-oscillator;105;84;114;85;96;113;103;85;94;110 16:18:25 INFO - PROCESS | 9645 | |5;imaging-gaussian-blur;103;104;106;140;141;123;137;119;135;129 16:18:25 INFO - PROCESS | 9645 | |6;imaging-darkroom;105;103;116;104;104;128;147;110;113;141 16:18:25 INFO - PROCESS | 9645 | |7;imaging-desaturate;101;105;139;122;129;148;125;142;146;104 16:18:25 INFO - PROCESS | 9645 | |8;json-parse-financial;86;93;90;76;71;73;91;74;75;75 16:18:25 INFO - PROCESS | 9645 | |9;json-stringify-tinderbox;77;95;101;103;111;78;83;104;85;83 16:18:25 INFO - PROCESS | 9645 | |10;stanford-crypto-aes;99;130;105;102;117;108;121;144;112;125 16:18:25 INFO - PROCESS | 9645 | |11;stanford-crypto-ccm;220;171;214;161;167;186;216;199;232;218 16:18:25 INFO - PROCESS | 9645 | |12;stanford-crypto-pbkdf2;252;235;266;205;241;265;242;313;235;287 16:18:25 INFO - PROCESS | 9645 | |13;stanford-crypto-sha256-iterative;93;87;116;82;99;84;104;77;88;105 16:18:25 INFO - PROCESS | 9645 | __end_tp_report 16:18:25 INFO - PROCESS | 9645 | __start_cc_report 16:18:25 INFO - PROCESS | 9645 | _x_x_mozilla_cycle_collect,1064 16:18:25 INFO - PROCESS | 9645 | __end_cc_report 16:18:25 INFO - PROCESS | 9645 | __startTimestamp1486772305877__endTimestamp 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | ------- Summary: start ------- 16:18:25 INFO - PROCESS | 9645 | Number of tests: 14 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#0] ai-astar Cycles:10 Average:151.30 Median:155.50 stddev:22.85 (14.7%) stddev-sans-first:20.81 16:18:25 INFO - PROCESS | 9645 | Values: 118.0 127.0 154.0 187.0 140.0 175.0 135.0 177.0 157.0 143.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#1] audio-beat-detection Cycles:10 Average:153.70 Median:157.50 stddev:23.97 (15.2%) stddev-sans-first:25.38 16:18:25 INFO - PROCESS | 9645 | Values: 158.0 122.0 160.0 177.0 156.0 139.0 134.0 131.0 203.0 157.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#2] audio-dft Cycles:10 Average:265.60 Median:264.50 stddev:17.56 (6.6%) stddev-sans-first:18.41 16:18:25 INFO - PROCESS | 9645 | Values: 258.0 269.0 239.0 260.0 274.0 294.0 256.0 294.0 254.0 258.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#3] audio-fft Cycles:10 Average:87.60 Median:88.50 stddev:12.38 (14.0%) stddev-sans-first:9.47 16:18:25 INFO - PROCESS | 9645 | Values: 112.0 67.0 87.0 97.0 83.0 75.0 91.0 90.0 93.0 81.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:84.50 stddev:11.76 (13.9%) stddev-sans-first:12.27 16:18:25 INFO - PROCESS | 9645 | Values: 105.0 84.0 114.0 85.0 96.0 113.0 103.0 85.0 94.0 110.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#5] imaging-gaussian-blur Cycles:10 Average:123.70 Median:132.00 stddev:15.09 (11.4%) stddev-sans-first:14.03 16:18:25 INFO - PROCESS | 9645 | Values: 103.0 104.0 106.0 140.0 141.0 123.0 137.0 119.0 135.0 129.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#6] imaging-darkroom Cycles:10 Average:117.10 Median:114.50 stddev:16.13 (14.1%) stddev-sans-first:16.50 16:18:25 INFO - PROCESS | 9645 | Values: 105.0 103.0 116.0 104.0 104.0 128.0 147.0 110.0 113.0 141.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#7] imaging-desaturate Cycles:10 Average:126.10 Median:134.00 stddev:17.90 (13.4%) stddev-sans-first:16.53 16:18:25 INFO - PROCESS | 9645 | Values: 101.0 105.0 139.0 122.0 129.0 148.0 125.0 142.0 146.0 104.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#8] json-parse-financial Cycles:10 Average:80.40 Median:81.00 stddev:8.54 (10.5%) stddev-sans-first:8.81 16:18:25 INFO - PROCESS | 9645 | Values: 86.0 93.0 90.0 76.0 71.0 73.0 91.0 74.0 75.0 75.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#9] json-stringify-tinderbox Cycles:10 Average:92.00 Median:80.50 stddev:12.24 (15.2%) stddev-sans-first:11.72 16:18:25 INFO - PROCESS | 9645 | Values: 77.0 95.0 101.0 103.0 111.0 78.0 83.0 104.0 85.0 83.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#10] stanford-crypto-aes Cycles:10 Average:116.30 Median:123.00 stddev:14.03 (11.4%) stddev-sans-first:13.41 16:18:25 INFO - PROCESS | 9645 | Values: 99.0 130.0 105.0 102.0 117.0 108.0 121.0 144.0 112.0 125.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#11] stanford-crypto-ccm Cycles:10 Average:198.40 Median:215.00 stddev:25.43 (11.8%) stddev-sans-first:25.75 16:18:25 INFO - PROCESS | 9645 | Values: 220.0 171.0 214.0 161.0 167.0 186.0 216.0 199.0 232.0 218.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:254.10 Median:258.50 stddev:30.32 (11.7%) stddev-sans-first:32.15 16:18:25 INFO - PROCESS | 9645 | Values: 252.0 235.0 266.0 205.0 241.0 265.0 242.0 313.0 235.0 287.0 16:18:25 INFO - PROCESS | 9645 | 16:18:25 INFO - PROCESS | 9645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:93.50 Median:85.50 stddev:12.23 (14.3%) stddev-sans-first:12.97 16:18:25 INFO - PROCESS | 9645 | Values: 93.0 87.0 116.0 82.0 99.0 84.0 104.0 77.0 88.0 105.0 16:18:25 INFO - PROCESS | 9645 | -------- Summary: end -------- 16:18:25 INFO - PROCESS | 9645 | 16:18:26 INFO - TEST-INFO | 9645: exit 0 16:18:26 INFO - TEST-OK | kraken | took 70210ms 16:18:26 INFO - SUITE-END | took 1188s 16:18:26 INFO - Completed test suite (00:19:48) 16:18:26 INFO - javascript benchmark 16:18:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6723.635986598827, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2476.49231907459, 2571.9485610287807, 2603.8258054524467, 2956.6599841018274, 2963.8814447422105, 3800.9619903800954, 3803.915883973068, 3827.0623697194155, 4081.1225586498895, 4122.608352206543, 3965.583613720561, 4085.8569950051756, 4384.846530371437, 4995.225740010304, 5009.597312752426, 3939.7833119178435, 3955.6044395560502, 4047.6154765297397, 4374.846880359188, 4468.285074388091, 4020.9396859047138, 4045.008972801668, 4113.856015039474, 4682.90634187316, 4692.085043416535, 3861.69106471483, 4181.477315335583, 4510.278355463127, 4587.747673877935, 4680.672352935296, 3760.905977350561, 3778.5276840394813, 3789.6399842014966, 3879.806009699532, 3906.667933225679, 3401.9829900850586, 3544.6987006104514, 3683.8710645127558, 3745.4756334113245, 4024.953512086849, 3847.076701591598, 3878.8751262133947, 4103.932977425861, 4112.02622741086, 4385.56144385562, 4269.423627810237, 4479.216137175981, 4889.33993910821, 5330.706811125387, 5335.466453354634, 4261.616454519077, 4288.76411797351, 4439.867833702385, 4513.187626227278, 4610.515895830923, 3940.541999460218, 4200.411942328076, 4403.779811009436, 4426.313921342197, 4648.233041548175, 4025.0339918419954, 4398.274284742982, 4954.927944674325, 5271.920921186109, 5670.688112153871, 4112.8202052912, 4116.423700681907, 4142.275101857163, 4333.761643109659, 5054.49455054499, 4063.3701776224734, 4277.7647229402055, 5236.9526094781195, 5538.006317725615, 5595.21666966625, 4294.012377153273, 4596.287575425815, 4799.784009719571, 5030.0, 5550.389457159709, 3761.6238376162164, 4175.498940127204, 4234.555371685929, 4319.33050377192, 4449.688521803443, 3600.42393217084, 3623.963760362363, 3834.5973672763957, 3941.625545573166, 4100.765083029756, 3142.0, 3385.9492107618407, 3527.735419843522, 3607.8376473058775, 3818.885433437001, 3980.7810570418906, 4151.750894946313, 4183.330667093251, 4255.936160957526, 4739.3601863748645, 4373.0, 4393.670474714345, 4492.989077457546, 4553.430821147356, 5080.466551012164, 3938.9561766131997, 3970.642642162219, 4044.312466880637, 4087.9355925257555, 4262.978685106555, 3837.424386342071, 3949.0522274653877, 4027.8791636250958, 4153.400940637815, 4235.174141042528, 4596.943032883095, 4690.0, 4747.006257371843, 5720.914186287126, 5813.476787089182, 4061.0, 4252.491030292143, 4790.520947905321, 5285.656432331886, 5613.46672066153, 4202.2646036944025, 4389.078293558261, 4634.791434385528, 4786.425628924551, 4788.61691064721, 4333.003409215835, 4403.3174857897075, 5475.863103422446, 5564.554835613222, 5587.525060369914, 3831.482749828849, 4395.318725597537, 4598.896264896334, 4637.628989680885, 5280.498352656491, 4120.75275483472, 4308.870733877989, 4463.4420697412825, 5139.331886854685, 5151.948480515297, 4120.835166593302, 4451.5754958413, 4557.384753058294, 4632.652551058617, 5206.167013277857, 4537.205988951986, 4577.779776808312, 4603.93094103582, 4617.799372163195, 4816.783244754065, 3911.8630847920185, 4154.3145381013, 4364.956350436582, 4411.536788637147, 4448.354988526581, 3854.4989151410136, 4167.5832416759295, 4253.000544872015, 4559.065391594781, 4860.0279944012045], "value": 4307.845117163406, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.6318660459168, 459.51980180711166, 466.5357968821024, 485.1558288577876, 485.15825043549455, 33313.83343083284, 38206.61793382066, 38244.80877595615, 38246.617533824654, 38353.23293534128, 39013.0, 39782.60217397825, 39868.00329991752, 39885.210904963984, 39949.40075898864, 8933.062028486996, 9234.6306147754, 9315.975242723294, 10377.740556486111, 10753.731156721105, 10518.105960993325, 10592.78814423711, 10597.311174773653, 10606.666533362686, 10629.0, 9044.954775226115, 10651.147908167328, 10655.84016239757, 10664.360138391721, 10674.252802303803, 10575.78848423035, 10610.991955764151, 10623.097036751886, 10627.41549214793, 10640.94679526605, 8834.514101724402, 9039.864402033974, 10575.629852955186, 10622.203334749925, 10641.829398766129, 34929.0, 34936.12659683503, 35049.474257886155, 35074.597016119325, 35172.82413587916, 3570.393033184365, 3621.692156166729, 3634.727395445352, 3645.2891686121216, 3762.868299609528, 26428.471430571473, 27247.046353377536, 27349.589756153673, 27444.588331175048, 27467.587986180224, 3066.877324907001, 3077.569140320357, 3121.469350210447, 3193.5209718542174, 3201.4557525220766, 431287.0, 435649.0, 437993.810030951, 440145.0, 441281.0, 44620.10759784851, 45882.54117458783, 51747.74126129345, 63087.6845615769, 63838.0, 50119.498805011484, 63025.68487157535, 66878.3312166872, 67354.0, 67463.98804017944, 14991.250437478082, 15112.848871511364, 15119.319630616648, 15142.54572362831, 15148.863835212404, 11398.601048963246, 11482.48328825205, 11517.942410287895, 11554.017908477705, 11594.76810463786, 19000.80999190018, 19537.0, 19825.504362391057, 19913.800861991484, 19955.102020409115, 6888.758893438706, 8216.671333146727, 8370.455920365279, 8396.244338009608, 8414.579271036424, 818.2799136759613, 832.6253186066294, 832.9541875196801, 850.5534594338022, 859.1151114352227, 28741.56887646687, 29337.853310733735, 29367.11898643044, 29761.851190743906, 29880.804767809044, 992.5483904823288, 1004.1464754958228, 1004.2719028704131, 1038.2732087538752, 1047.2250534604339, 14513.201773902325, 14983.700325993419, 15035.774463383059, 15362.846371536141, 15368.077915325117, 605354.0, 609903.0, 609992.0, 610340.0, 610660.0, 56219.0, 56462.717686411306, 56470.152947705814, 56714.149287760716, 56801.0, 1268.2137075013366, 1325.4234408032537, 1335.1388354511091, 1335.5859683498147, 1344.536135033412, 24120.777647595827, 28641.570376443953, 29122.417551648425, 29170.708292917647, 29281.560776588794, 47208.76395618, 47304.0, 47596.0, 47869.5213047865, 47934.76032619815, 496100.0, 497591.0, 497792.0, 499211.503942478, 499248.0, 23978.0, 24261.150859719826, 24618.876905615358, 24670.383240419134, 39953.80023099866, 32396.190095247806, 39120.80439597784, 39499.60500394959, 39761.0, 40577.0, 32510.0, 33538.993830185136, 33620.49569256512, 34466.655333446346, 34676.306473869874, 33109.0, 33290.66709332875, 34171.82914085413, 34197.0, 34539.4819077709, 32914.67085329212, 33960.66039339674, 34065.14837129092, 34175.829120854236, 34321.97034088981, 27194.728052720013, 28824.423511530073, 29423.0, 29550.556741648463, 29571.0, 21878.418815160025, 21956.341309760734, 22078.22726204575, 22145.667814982466, 22252.109915603196], "value": 18962.325930181094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.76452130290767, 377.41312259436575, 378.5381834162321, 387.9854181315859, 414.05595242846323, 18748.62502749944, 19737.21051157952, 19911.30310439135, 20383.0, 20515.897420512913, 18699.62600747984, 18825.05874706266, 19193.32823351183, 19297.4210773677, 19298.421047368567, 4089.3865920111925, 4898.0, 5140.490538898411, 5202.713850738208, 5266.92099618506, 4460.955390446103, 5274.709890955995, 5280.735963201843, 5285.312909321783, 5285.4450282720145, 5210.791568337262, 5271.4464981177, 5287.867803304929, 5288.788448462057, 5296.496832800878, 5627.127795191752, 5707.828765137032, 5730.828075157752, 5742.827715168552, 5751.827445176651, 4405.559444055569, 4924.285978533093, 5135.897282054375, 5138.7173705446185, 5149.176131818891, 12903.35483225835, 13029.934850325782, 13053.955683545388, 13064.346782660925, 14898.851011489964, 2465.790407815338, 2473.2703852363597, 2486.6021436570245, 2488.925332240036, 2499.4376265340334, 9598.568064437048, 9667.516624168764, 10183.694489165337, 10381.532831022621, 14956.40174393023, 1714.554215903874, 1742.4337090445656, 1748.2744660965811, 1778.2264714849082, 1793.3364655077705, 94013.70952016715, 127291.3635431817, 128771.35614321868, 129109.35445322713, 129243.0, 11548.307101573933, 11846.940765296118, 11876.88123118775, 11878.88121118795, 11923.880761192451, 15419.922900385427, 15429.768553471707, 15454.304556294994, 15460.613484662748, 15501.689966200838, 6637.734490620418, 8197.79505512367, 8280.627371768285, 8334.541600211925, 8373.832523349622, 7779.338756205672, 7786.338161256243, 7795.610219489116, 7946.880796788053, 7958.641861116148, 4445.3998710174355, 4475.0, 4705.247160454379, 4787.3776409067295, 4892.290617860462, 2004.6892731626622, 2007.5181956330375, 2073.4919944613666, 2081.4484161697164, 2083.91664333428, 938.9765255868522, 967.777411195415, 972.2824704949683, 987.7975015121883, 1004.6282875336173, 19833.710808797383, 20925.686114708293, 21033.26383576598, 21072.578548428944, 21158.788412115682, 893.9687110951086, 984.5471083301618, 991.1278075293695, 1027.5376080763685, 1028.4960369418932, 8434.849113476024, 10645.190317646018, 10760.838587421194, 10909.39998300101, 10975.83536246957, 297658.5351219734, 330887.0, 362086.0, 672340.0, 674234.0, 118675.0, 147234.0, 149961.0, 150026.7495987561, 153459.0, 944.6127087893931, 950.9239260859115, 974.770928831724, 976.023976023976, 980.0444566547701, 17236.39672611503, 18261.178246978652, 18325.725114123565, 18329.81670183281, 18465.90767046156, 58482.707586461795, 59214.0, 59664.50838729066, 61015.0, 61114.0, 84945.57527212324, 85141.0, 85295.14704852871, 85849.57075214584, 86192.13807861842, 7469.514481558681, 7472.626368681435, 8122.959385203036, 8251.876221856557, 8465.873011904949, 10630.52162652698, 12576.74846503083, 12604.621861344174, 12862.935685321514, 12952.611421657366, 10450.111740502143, 10648.307859989076, 11122.833157502806, 11291.983721465107, 11296.548138074386, 8924.0, 9314.813703725751, 10697.786044278915, 10917.39954302521, 11099.389533575732, 10823.242373033812, 11266.267692599955, 11315.660530184108, 11444.771104577696, 11464.713382165513, 7015.438764898898, 7923.128455869734, 8133.8373232536205, 8235.464694794819, 8352.707655232038, 8354.87467687973, 11313.943430282796, 11321.377324247245, 11360.602378916697, 11364.54541818351], "value": 9250.002397402766, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.092764239423, 1413.109740863256, 1416.992915035424, 1431.976137062001, 1437.1018113678952, 8130.390220733447, 8147.981502312211, 8447.662093516252, 8616.138386161372, 8655.78360540986, 8383.329333653293, 8513.148685131484, 8518.488890666564, 8524.786880327994, 8558.315334773219, 2232.620454522735, 2277.0, 2284.383216531544, 2287.421679041456, 2290.438842483594, 2261.6720575516542, 2301.436148143707, 2303.9769602304013, 2304.3893368257427, 2315.9305220843316, 2244.2145249162827, 2253.3465295064493, 2273.9772602274015, 2279.133929106937, 2280.680704701343, 2166.9349919502442, 2232.821374290053, 2248.471039693008, 2253.43664083979, 2262.7171603549473, 1974.1511150205406, 2012.466696325475, 2054.7739748627637, 2238.955220895573, 2284.474570848714, 7823.843523129505, 8451.746447606582, 8522.786930326729, 8576.05663377028, 8585.527795971218, 4489.595936365743, 5081.441041485434, 5159.458256883011, 5228.8692782680355, 5255.86860328491, 3421.555197824292, 3477.539433437962, 3479.3389256041273, 3529.517602606911, 3557.288542291552, 1158.9652310430702, 1160.2052593973071, 1163.96508104757, 1169.0297053445704, 1194.0, 10993.340399576051, 11012.834807477895, 11058.889411105867, 11068.225224234226, 11084.61203857861, 4256.2551553478015, 4423.225935461282, 4475.977620111878, 4507.81968721254, 4508.233600287894, 8759.211670949519, 8802.987656419593, 8814.823703525894, 8815.382923195315, 8852.937647482344, 5074.2895994560795, 5123.001014802152, 5131.897362052738, 5138.614603904722, 5142.357205349332, 4644.814207431665, 4651.651126165551, 4719.480857105716, 4726.038693942044, 4793.209120495079, 3596.536372584788, 3606.1886075633297, 3667.0, 3685.0787303174206, 3700.4634328022285, 976.7655762616921, 1128.5711429656678, 1132.371533798733, 1143.0512674480162, 1150.9769804603861, 764.2166779051406, 764.8546776112632, 785.9389823738022, 787.6967367563415, 802.1377019703725, 5345.893082138336, 5463.972680136574, 5514.558835293167, 5589.944100559023, 5595.832125036255, 446.6002927379977, 530.254991736603, 549.181719238332, 554.6644280210495, 557.9009351577386, 3624.130208749881, 3655.853765849389, 3747.718921080875, 3770.472133901256, 3774.6602805747616, 9098.681546145854, 9460.8580871287, 9570.95214523937, 9807.215422766161, 9851.802963940825, 9126.041765614647, 10095.394276343442, 10139.594416223415, 10142.29003969715, 10160.796784064278, 7481.177070522238, 7911.604419778988, 7933.167017463199, 7939.64271607779, 7971.44199906001, 4428.778561071869, 5466.535344495763, 5590.329160500766, 5661.830145095653, 5755.050416681201, 9112.271018318386, 9131.954340228256, 9202.217811486098, 9292.860607091034, 9363.250939924927, 9235.26117910579, 9470.479123648265, 9491.240700743785, 9537.52168413897, 9537.570809313458, 6698.631575263407, 6776.424003959719, 6781.6270105144695, 6789.592624442352, 6792.93207067923, 6716.496262780213, 7798.805029874298, 7852.646630901738, 7893.565853878092, 7909.644066016928, 5998.040313549811, 7143.678534465857, 7180.856382872418, 7182.784516464514, 7228.566286022856, 7005.334493223136, 7078.0, 7088.964555177191, 7158.248383919614, 7158.606276654834, 6283.465905398092, 6323.683815809283, 6328.24061112651, 6402.455791257609, 6417.550771445954, 4836.782344794422, 4879.756012199305, 4929.112759703288, 4964.67729597575, 5148.613853960893, 4406.4051353066925, 4903.803847846046, 4962.627802914723, 5010.248462730619, 5084.3644544431945], "value": 4264.490383079586, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.5185477348298, 334.60944189113394, 347.65234765234766, 353.4556782554865, 354.67724370822526, 4104.794760261982, 4629.537046295369, 5351.625386222966, 5454.536364409025, 5470.2615146955195, 4631.560001799832, 4694.8028252795575, 4972.651914365996, 5131.202909846715, 5286.682799032061, 3707.3141468828217, 4201.941048886054, 4209.621134097931, 4217.261979153651, 4238.682098842583, 3800.2969450651717, 3969.729686500321, 4230.863413975938, 4246.851360202393, 4247.723897946639, 4094.651954583849, 4138.2758017347, 4152.813123409439, 4154.584541545836, 4158.355454904495, 3672.6143754905615, 4005.2790497710403, 4137.193247316774, 4143.461350024492, 4169.708120431571, 3622.768258791997, 3683.5395575553057, 3693.098054501934, 3694.704423646129, 3711.981440092782, 4233.111046680201, 4883.92553638199, 5127.256547800548, 5146.716930568818, 5228.686278823283, 4527.886802829923, 4566.433566433567, 4576.496585375606, 4588.0, 4595.2188128018315, 4286.906838756128, 4441.511433742286, 4771.33201351811, 5614.6631202128, 5681.289838770153, 3682.0853156358544, 3732.3654978653694, 3769.8869033929027, 3826.617338266179, 3839.4432807242792, 4916.852494425173, 5472.589555783293, 5600.635958662715, 5800.941990580082, 5941.881162376728, 3109.8445077746023, 3187.6493585705553, 3826.7512611680154, 3860.864869729546, 3871.032241939515, 3336.349411864663, 3336.4828451590042, 3466.5666791651042, 3494.336076145524, 3968.7817170055346, 4104.8768536943935, 4422.115576884636, 4595.0, 4605.016660588567, 4644.164050470948, 4462.397576327219, 4980.904201075758, 4993.90012199754, 5585.3297604287745, 5613.153620827528, 1229.4528934624186, 1236.3076677060874, 1276.2897717059186, 1277.4187744576195, 1281.3785314122642, 730.5689643110591, 850.7961234852738, 862.2843040276556, 869.8995770350512, 870.777951622345, 4296.4414626098405, 4828.782704778293, 4894.583960363338, 5045.974770126126, 5053.31780209667, 4996.325496057985, 5086.830029093329, 5340.652857564246, 5527.50252477279, 5534.169874518854, 4226.992178715086, 4428.867133985921, 4732.503087175797, 5028.874278143076, 5049.0, 2505.523950449409, 2649.7217792131933, 2894.609227754226, 2934.7358737713707, 2992.772963085168, 5459.972700136554, 5823.505002074871, 5823.941760582425, 5851.741875496788, 5896.233489646319, 4790.473047964721, 5453.781848726086, 5773.0, 5773.393793651606, 5910.734016969247, 6040.244969378916, 6040.577159598786, 6044.788432404845, 6064.302605200459, 6073.99779036454, 5559.360673522435, 5848.239728835224, 5850.473457388855, 5853.35613082552, 5893.292804863444, 5707.001274776981, 5747.453991870766, 5782.45066718661, 5786.855328616818, 5802.94197058024, 5596.460973232393, 5727.0550359190265, 5972.283326000907, 6032.758689652364, 6132.233470816148, 3799.4870692456166, 3867.5358956925347, 3924.2543916655745, 3926.9214615706956, 3934.1738234970976, 5776.389756402248, 5777.884442311214, 5833.562482813891, 5846.35690074083, 5850.829834033296, 4759.476457589593, 5321.472300657157, 5658.405867383865, 5695.800646977336, 5714.485696287354, 4749.833755818529, 5050.747462626928, 5095.13382724945, 5686.09022556389, 5716.971415142898, 5591.832245032493, 5597.972010139923, 5616.494515493625, 5648.774049037993, 5653.491185793298, 4295.162443323522, 4440.823996241883, 5374.641195684512, 5469.753861076181, 5718.656880587178, 5566.137248726453, 5580.0748741685675, 5600.579956503197, 5639.915401269066, 5644.3508996465935], "value": 4062.4661632615807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.646019379632, 685.8045457044743, 747.0587060304015, 766.5592284436449, 769.6651956398966, 11605.593804216855, 14906.403743850233, 14975.0, 15001.249937503137, 15028.549143525684, 13475.326233688302, 13624.659383515416, 13651.044426890121, 13657.726845463085, 13664.248466334348, 686.6120641837346, 711.5197241861748, 843.1905370843982, 873.9825203495926, 876.7545087375545, 873.6199753107387, 880.1286726141107, 880.7930136417937, 880.7974165941837, 885.995570022149, 921.410297409655, 924.4915296586851, 925.6852670092168, 926.1664501948233, 929.8419268724333, 821.9314890642171, 844.8563744163507, 920.6271460058687, 921.2169655792557, 924.8381533231658, 683.4907993544841, 683.9350261725128, 684.3020119478155, 685.9211190713082, 688.1948120698794, 12544.498220071277, 14164.645883852883, 14663.560093197115, 15133.167675777828, 15145.0, 13895.166290022631, 14198.645033874236, 14217.431302747878, 14265.857341426557, 14277.214753188571, 11020.834687479693, 11084.722881927935, 11108.0, 11158.944205279004, 11493.0, 2980.7019298070236, 3619.6199399062984, 3642.7996460194677, 3651.2697460508007, 3764.9058773530605, 95853.52073239659, 97807.0219297805, 98120.03759924833, 98306.0, 99885.50057249668, 10609.681709548726, 10623.043926046694, 10771.83842242367, 10957.726056848483, 11483.827742583868, 20416.591668166555, 22372.32883013512, 22949.311520654406, 23337.649935250985, 23529.647055294183, 6548.705308261045, 7418.6646403647865, 7611.238876112455, 7689.346405555479, 7821.178776228414, 3980.1840622672285, 4097.221527909687, 4097.508299004079, 4099.262132816062, 4102.815373308208, 3538.5399898013093, 3549.0594992327055, 3598.8020658864016, 3632.2372301816386, 3634.854605815791, 656.9474442044625, 662.2020465339255, 666.190578447189, 674.5008693566823, 675.8242856857253, 516.6744950681046, 578.254052272572, 589.7316720891984, 591.4942723970943, 595.2886300870418, 8006.0, 9216.493092879822, 9832.508374581384, 9845.0, 9987.250956178315, 606.9423404776539, 656.228931006066, 660.2341284110505, 667.3993405934698, 692.375414704024, 4193.91921354104, 4585.358049873021, 4668.85993420198, 4717.631886752872, 4735.526447355306, 65939.01091483631, 72089.27910720826, 73832.0, 89226.43094276157, 97155.51422242844, 69119.0, 69917.0, 70349.64825175841, 71932.64033679903, 73127.53744925071, 51953.70115747137, 58789.0, 64811.0, 65686.34313656802, 66465.00604981858, 9795.9020409795, 10331.276810623485, 10482.213833962636, 10496.895031049591, 10519.368837869752, 45463.54536454725, 46245.76877115727, 46706.53293467022, 46919.29621055755, 47257.0, 49150.0, 54815.0, 58159.4184058154, 58507.0, 59483.70258148855, 11399.259048161843, 11732.94133529327, 11793.823092653445, 11849.289042657469, 11876.82184767212, 13518.59444216675, 13523.323833808467, 13567.0, 13573.195323287177, 13804.240766757523, 12565.057620678303, 12615.81076283838, 12907.354632268536, 12988.545400910923, 13121.606351809462, 12464.813027804408, 12609.810852837032, 12630.557930472389, 12863.0, 13016.414261358452, 11759.764804703687, 11796.587119450778, 11943.283402995849, 12109.273443593413, 12317.137800353888, 10012.39925604466, 10017.949910250401, 10037.397756134655, 10338.741531461774, 10419.895801042196, 8210.973628296462, 8665.0, 9404.670836520689, 10279.359509968546, 10399.480025998519], "value": 7057.01993889671, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1958.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 127.0, 154.0, 187.0, 140.0, 175.0, 135.0, 177.0, 157.0, 143.0], "value": 151.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 160.0, 177.0, 156.0, 139.0, 134.0, 131.0, 203.0, 157.0], "value": 153.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [258.0, 269.0, 239.0, 260.0, 274.0, 294.0, 256.0, 294.0, 254.0, 258.0], "value": 265.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 67.0, 87.0, 97.0, 83.0, 75.0, 91.0, 90.0, 93.0, 81.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 84.0, 114.0, 85.0, 96.0, 113.0, 103.0, 85.0, 94.0, 110.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 104.0, 106.0, 140.0, 141.0, 123.0, 137.0, 119.0, 135.0, 129.0], "value": 123.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 103.0, 116.0, 104.0, 104.0, 128.0, 147.0, 110.0, 113.0, 141.0], "value": 117.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 105.0, 139.0, 122.0, 129.0, 148.0, 125.0, 142.0, 146.0, 104.0], "value": 126.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 93.0, 90.0, 76.0, 71.0, 73.0, 91.0, 74.0, 75.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 95.0, 101.0, 103.0, 111.0, 78.0, 83.0, 104.0, 85.0, 83.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 130.0, 105.0, 102.0, 117.0, 108.0, 121.0, 144.0, 112.0, 125.0], "value": 116.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 171.0, 214.0, 161.0, 167.0, 186.0, 216.0, 199.0, 232.0, 218.0], "value": 198.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [252.0, 235.0, 266.0, 205.0, 241.0, 265.0, 242.0, 313.0, 235.0, 287.0], "value": 254.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 116.0, 82.0, 99.0, 84.0, 104.0, 77.0, 88.0, 105.0], "value": 93.5, "unit": "score"}]}]} 16:18:27 INFO - Return code: 0 16:18:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:18:27 ERROR - # TBPL SUCCESS # 16:18:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:27 INFO - 2017-02-10 16:18:27.130 screenresolution[12229:65926] starting screenresolution argv=screenresolution get 16:18:27 INFO - 2017-02-10 16:18:27.133 screenresolution[12229:65926] Display 0: 1600x1200x32@60 16:18:27 INFO - 2017-02-10 16:18:27.140 screenresolution[12230:65930] starting screenresolution argv=screenresolution list 16:18:27 INFO - Available Modes on Display 0 16:18:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:27 INFO - Intel Iris: 16:18:27 INFO - Chipset Model: Intel Iris 16:18:27 INFO - Type: GPU 16:18:27 INFO - Bus: Built-In 16:18:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:27 INFO - Vendor: Intel (0x8086) 16:18:27 INFO - Device ID: 0x0a2e 16:18:27 INFO - Revision ID: 0x0009 16:18:27 INFO - Displays: 16:18:27 INFO - Display: 16:18:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:27 INFO - Display Serial Number: 1600x1200 60 16:18:27 INFO - Main Display: Yes 16:18:27 INFO - Mirror: Off 16:18:27 INFO - Online: Yes 16:18:27 INFO - Rotation: Supported 16:18:27 INFO - Return code: 0 16:18:27 INFO - Running post-action listener: _resource_record_post_action 16:18:27 INFO - [mozharness: 2017-02-11 00:18:27.404409Z] Finished run-tests step (success) 16:18:27 INFO - Running post-run listener: _resource_record_post_run 16:18:27 INFO - Total resource usage - Wall time: 1208s; CPU: 58.0%; Read bytes: 40368640; Write bytes: 521644544; Read time: 404; Write time: 2429 16:18:27 INFO - TinderboxPrint: CPU usage
57.6% 16:18:27 INFO - TinderboxPrint: I/O read bytes / time
40,368,640 / 404 16:18:27 INFO - TinderboxPrint: I/O write bytes / time
521,644,544 / 2,429 16:18:27 INFO - TinderboxPrint: CPU idle
2,049.9 (42.4%) 16:18:27 INFO - TinderboxPrint: CPU system
1,032.3 (21.4%) 16:18:27 INFO - TinderboxPrint: CPU user
1,751.5 (36.2%) 16:18:27 INFO - TinderboxPrint: Swap in / out
251,858,944 / 0 16:18:27 INFO - install - Wall time: 20s; CPU: 53.0%; Read bytes: 212817920; Write bytes: 213423616; Read time: 15599; Write time: 419 16:18:27 INFO - run-tests - Wall time: 1189s; CPU: 58.0%; Read bytes: 35797504; Write bytes: 306754560; Read time: 372; Write time: 2005 16:18:28 INFO - Running post-run listener: _upload_blobber_files 16:18:28 INFO - Blob upload gear active. 16:18:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:18:28 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 16:18:28 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'] 16:18:28 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 16:18:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:18:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:18:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:29 INFO - (blobuploader) - INFO - Done attempting. 16:18:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:18:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:30 INFO - (blobuploader) - INFO - Done attempting. 16:18:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:18:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:18:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:31 INFO - (blobuploader) - INFO - Done attempting. 16:18:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:18:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:18:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:33 INFO - (blobuploader) - INFO - Done attempting. 16:18:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:18:33 INFO - Return code: 0 16:18:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:18:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:18:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/405e161d27fdb7e78e8a535339d0f3210993b7c3dc9844bbdc2bbea9278c2101f2024113a7608d63110c88fb13b58523d5e881e70f0d758a81c5b84c435aeae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f3f540952ccc5d4719cfb848b4d9cb7300371402e75fea7bd0f1b0025d947fa0cd6229491bec1adf6136b235828fe1c1b043f9b390a901543cfd7db615d0e5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f192f9212582003540592d3cdce142a601547635509d7bff6ff5d533c0e541cf2f8039a7b2dd7f6c311361b16957ba926c999a802b1b36b964e94935fa0542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51809f2907e2d95cfac79bfa1254e87184036f3e53c2f452188a869d1b49f9e5fc7c57fafac58becd7907d2effb88a414482cd6a91715c0acbb2eec36575c3a5"} 16:18:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:18:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:18:33 INFO - Contents: 16:18:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/405e161d27fdb7e78e8a535339d0f3210993b7c3dc9844bbdc2bbea9278c2101f2024113a7608d63110c88fb13b58523d5e881e70f0d758a81c5b84c435aeae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f3f540952ccc5d4719cfb848b4d9cb7300371402e75fea7bd0f1b0025d947fa0cd6229491bec1adf6136b235828fe1c1b043f9b390a901543cfd7db615d0e5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f192f9212582003540592d3cdce142a601547635509d7bff6ff5d533c0e541cf2f8039a7b2dd7f6c311361b16957ba926c999a802b1b36b964e94935fa0542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51809f2907e2d95cfac79bfa1254e87184036f3e53c2f452188a869d1b49f9e5fc7c57fafac58becd7907d2effb88a414482cd6a91715c0acbb2eec36575c3a5"} 16:18:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:18:33 INFO - Copying logs to upload dir... 16:18:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:18:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.384915 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-02-10 16:18:33.384221) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 16:18:33.387704) ========= 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.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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/405e161d27fdb7e78e8a535339d0f3210993b7c3dc9844bbdc2bbea9278c2101f2024113a7608d63110c88fb13b58523d5e881e70f0d758a81c5b84c435aeae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f3f540952ccc5d4719cfb848b4d9cb7300371402e75fea7bd0f1b0025d947fa0cd6229491bec1adf6136b235828fe1c1b043f9b390a901543cfd7db615d0e5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f192f9212582003540592d3cdce142a601547635509d7bff6ff5d533c0e541cf2f8039a7b2dd7f6c311361b16957ba926c999a802b1b36b964e94935fa0542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51809f2907e2d95cfac79bfa1254e87184036f3e53c2f452188a869d1b49f9e5fc7c57fafac58becd7907d2effb88a414482cd6a91715c0acbb2eec36575c3a5"} build_url:https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg program finished with exit code 0 elapsedTime=0.010778 build_url: 'https://queue.taskcluster.net/v1/task/dxCbUAReT-6sFVFfp4TIbw/artifacts/public/build/target.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/405e161d27fdb7e78e8a535339d0f3210993b7c3dc9844bbdc2bbea9278c2101f2024113a7608d63110c88fb13b58523d5e881e70f0d758a81c5b84c435aeae0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f3f540952ccc5d4719cfb848b4d9cb7300371402e75fea7bd0f1b0025d947fa0cd6229491bec1adf6136b235828fe1c1b043f9b390a901543cfd7db615d0e5a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f192f9212582003540592d3cdce142a601547635509d7bff6ff5d533c0e541cf2f8039a7b2dd7f6c311361b16957ba926c999a802b1b36b964e94935fa0542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51809f2907e2d95cfac79bfa1254e87184036f3e53c2f452188a869d1b49f9e5fc7c57fafac58becd7907d2effb88a414482cd6a91715c0acbb2eec36575c3a5"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 16:18:33.436047) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 16:18:33.436619) ========= 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.kOEunjVpEH/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.xEtv5UuiWL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.005561 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 16:18:33.487711) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 16:18:33.488170) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 16:18:33.488603) ========= ========= Total master_lag: 0.22 =========