builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0241 starttime: 1480418573.96 results: success (0) buildid: 20161129010327 builduid: 37a20bc706d74ea6ac565694c532b697 revision: 94dac74c74a6e462fbbf9a1e1cad9fda3024122e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:53.959333) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:53.959801) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:53.960080) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:53.984758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:53.985045) ========= 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.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 03:22:53-- 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% 10.7M=0.001s 2016-11-29 03:22:54 (10.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:54.110297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:54.110696) ========= 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.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.021258 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:54.159708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 03:22:54.160056) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94dac74c74a6e462fbbf9a1e1cad9fda3024122e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94dac74c74a6e462fbbf9a1e1cad9fda3024122e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 03:22:54,245 truncating revision to first 12 chars 2016-11-29 03:22:54,245 Setting DEBUG logging. 2016-11-29 03:22:54,246 attempt 1/10 2016-11-29 03:22:54,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94dac74c74a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 03:22:56,048 unpacking tar archive at: mozilla-aurora-94dac74c74a6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.060255 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 03:22:56.304227) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:56.304499) ========= script_repo_revision: 94dac74c74a6e462fbbf9a1e1cad9fda3024122e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:56.304915) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:56.305194) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 03:22:56.325251) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-29 03:22:56.325514) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:22:56 INFO - MultiFileLogger online at 20161129 03:22:56 in /builds/slave/test 03:22:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:22:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:22:56 INFO - {'append_to_log': False, 03:22:56 INFO - 'base_work_dir': '/builds/slave/test', 03:22:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:22:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:22:56 INFO - 'branch': 'Mozilla-Aurora', 03:22:56 INFO - 'buildbot_json_path': 'buildprops.json', 03:22:56 INFO - 'config_files': ('talos/mac_config.py',), 03:22:56 INFO - 'default_actions': ('clobber', 03:22:56 INFO - 'read-buildbot-config', 03:22:56 INFO - 'download-and-extract', 03:22:56 INFO - 'populate-webroot', 03:22:56 INFO - 'create-virtualenv', 03:22:56 INFO - 'install', 03:22:56 INFO - 'run-tests'), 03:22:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:22:56 INFO - 'download_minidump_stackwalk': True, 03:22:56 INFO - 'download_symbols': 'ondemand', 03:22:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:22:56 INFO - 'tooltool.py': '/tools/tooltool.py', 03:22:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:22:56 INFO - '/tools/misc-python/virtualenv.py')}, 03:22:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:22:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:22:56 INFO - 'installer_path': 'installer.exe', 03:22:56 INFO - 'log_level': 'info', 03:22:56 INFO - 'log_name': 'talos', 03:22:56 INFO - 'log_to_console': True, 03:22:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:22:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:22:56 INFO - 'opt_config_files': (), 03:22:56 INFO - 'pip_index': False, 03:22:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:56 INFO - 'cmd': ('bash', 03:22:56 INFO - '-c', 03:22:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:56 INFO - 'enabled': True, 03:22:56 INFO - 'halt_on_failure': False, 03:22:56 INFO - 'name': 'check_screen_resolution'},), 03:22:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:56 INFO - 'cmd': ('bash', 03:22:56 INFO - '-c', 03:22:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:56 INFO - 'enabled': True, 03:22:56 INFO - 'halt_on_failure': False, 03:22:56 INFO - 'name': 'check_screen_resolution'},), 03:22:56 INFO - 'run_cmd_checks_enabled': True, 03:22:56 INFO - 'sps_profile': False, 03:22:56 INFO - 'sps_profile_interval': 0, 03:22:56 INFO - 'suite': 'dromaeojs-e10s', 03:22:56 INFO - 'system_bits': '32', 03:22:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:22:56 INFO - 'title': 't-yosemite-r7-0241', 03:22:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:22:56 INFO - 'use_talos_json': True, 03:22:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:22:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:22:56 INFO - 'work_dir': 'build'} 03:22:56 INFO - [mozharness: 2016-11-29 11:22:56.531024Z] Running clobber step. 03:22:56 INFO - Running pre-action listener: _resource_record_pre_action 03:22:56 INFO - Running main action method: clobber 03:22:56 INFO - rmtree: /builds/slave/test/build 03:22:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:22:58 INFO - Running post-action listener: _resource_record_post_action 03:22:58 INFO - [mozharness: 2016-11-29 11:22:58.055990Z] Finished clobber step (success) 03:22:58 INFO - [mozharness: 2016-11-29 11:22:58.056194Z] Running read-buildbot-config step. 03:22:58 INFO - Running pre-action listener: _resource_record_pre_action 03:22:58 INFO - Running main action method: read_buildbot_config 03:22:58 INFO - Using buildbot properties: 03:22:58 INFO - { 03:22:58 INFO - "project": "", 03:22:58 INFO - "product": "firefox", 03:22:58 INFO - "script_repo_revision": "production", 03:22:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:22:58 INFO - "repository": "", 03:22:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:22:58 INFO - "buildid": "20161129010327", 03:22:58 INFO - "pgo_build": "False", 03:22:58 INFO - "basedir": "/builds/slave/test", 03:22:58 INFO - "buildnumber": 464, 03:22:58 INFO - "slavename": "t-yosemite-r7-0241", 03:22:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:22:58 INFO - "platform": "yosemite_r7", 03:22:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:22:58 INFO - "branch": "mozilla-aurora", 03:22:58 INFO - "repo_path": "releases/mozilla-aurora", 03:22:58 INFO - "revision": "94dac74c74a6e462fbbf9a1e1cad9fda3024122e", 03:22:58 INFO - "stage_platform": "macosx64", 03:22:58 INFO - "builduid": "37a20bc706d74ea6ac565694c532b697", 03:22:58 INFO - "slavebuilddir": "test" 03:22:58 INFO - } 03:22:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:22:58 INFO - Running post-action listener: _resource_record_post_action 03:22:58 INFO - [mozharness: 2016-11-29 11:22:58.060590Z] Finished read-buildbot-config step (success) 03:22:58 INFO - [mozharness: 2016-11-29 11:22:58.060754Z] Running download-and-extract step. 03:22:58 INFO - Running pre-action listener: _resource_record_pre_action 03:22:58 INFO - Running main action method: download_and_extract 03:22:58 INFO - mkdir: /builds/slave/test/build/tests 03:22:58 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')]} 03:22:58 INFO - trying https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:22:59 INFO - Downloaded 1199 bytes. 03:22:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:22:59 INFO - Using the following test package requirements: 03:22:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:22:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:22:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:22:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'jsshell-mac.zip'], 03:22:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:22:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:22:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:22:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:22:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:22:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:22:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:22:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:00 INFO - Http code: 200 03:23:00 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 03:23:00 INFO - x-amz-cf-id: t7JoQ7z3XV8DkCWPFsaciPV_rruXfH0WcGw4D2tN5UFCUWUlFu5Gjw== 03:23:00 INFO - x-amz-version-id: pkI0NUHYrZk2cgVJXUkh53OuvM4C1wX3 03:23:00 INFO - Expected file size: 16283335 03:23:00 INFO - Obtained file size: 16283335 03:23:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:23:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:23:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:23:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:07 INFO - Http code: 200 03:23:07 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 03:23:07 INFO - x-amz-cf-id: u_2Eq3stC4tJNumraiax4S5QgsfQZMGGvvxvJmpH7RqlvGAMUMNeIQ== 03:23:07 INFO - x-amz-version-id: pkI0NUHYrZk2cgVJXUkh53OuvM4C1wX3 03:23:07 INFO - Expected file size: 16283335 03:23:07 INFO - Obtained file size: 16283335 03:23:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:23:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:23:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:23:14 INFO - Http code: 200 03:23:14 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 03:23:14 INFO - x-amz-cf-id: bjq659XCrnoubb7_5n4wUyvixpa3iwWR_NqviaiSCYMG3TwolBrN7w== 03:23:14 INFO - x-amz-version-id: 9RUUcrWUavW8r82wz876GKqkNjskwtVq 03:23:14 INFO - Expected file size: 14409038 03:23:14 INFO - Obtained file size: 14409038 03:23:15 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')]} 03:23:15 INFO - trying https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:23:21 INFO - Downloaded 109801291 bytes. 03:23:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:21 INFO - mkdir: /builds/slave/test/properties 03:23:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:23:21 INFO - Writing to file /builds/slave/test/properties/build_url 03:23:21 INFO - Contents: 03:23:21 INFO - build_url:https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:22 INFO - Running post-action listener: _resource_record_post_action 03:23:22 INFO - Running post-action listener: set_extra_try_arguments 03:23:22 INFO - [mozharness: 2016-11-29 11:23:22.058079Z] Finished download-and-extract step (success) 03:23:22 INFO - [mozharness: 2016-11-29 11:23:22.058236Z] Running populate-webroot step. 03:23:22 INFO - Running pre-action listener: _resource_record_pre_action 03:23:22 INFO - Running main action method: populate_webroot 03:23:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:23:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:23:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:23:22 INFO - '--tppagecycles', 03:23:22 INFO - '7'], 03:23:22 INFO - 'tests': ['tsvgm']}}, 03:23:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:23:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:23:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:23:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:23:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['damp', 'tps']}, 03:23:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'tests': ['damp', 'tps']}, 03:23:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['dromaeo_dom']}, 03:23:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:23:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['basic_compositor_video']}, 03:23:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:23:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'other-e10s': {'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['a11yr', 03:23:22 INFO - 'ts_paint', 03:23:22 INFO - 'tpaint', 03:23:22 INFO - 'sessionrestore', 03:23:22 INFO - 'sessionrestore_no_auto_restore', 03:23:22 INFO - 'tabpaint']}, 03:23:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['tsvgx', 03:23:22 INFO - 'tsvgr_opacity', 03:23:22 INFO - 'tart', 03:23:22 INFO - 'tscrollx', 03:23:22 INFO - 'cart']}, 03:23:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:23:22 INFO - 'tsvgr_opacity', 03:23:22 INFO - 'tart', 03:23:22 INFO - 'tscrollx', 03:23:22 INFO - 'cart']}, 03:23:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'talos_options': ['--disable-e10s'], 03:23:22 INFO - 'tests': ['tp5o']}, 03:23:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'tests': ['tp5o']}, 03:23:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'talos_options': ['--disable-e10s', 03:23:22 INFO - '--xperf_path', 03:23:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:22 INFO - 'tests': ['tp5n']}, 03:23:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:22 INFO - 'talos_options': ['--xperf_path', 03:23:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:22 INFO - 'tests': ['tp5n']}}, 03:23:22 INFO - 'talos.zip': {'path': '', 03:23:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:23:22 INFO - Running post-action listener: _resource_record_post_action 03:23:22 INFO - [mozharness: 2016-11-29 11:23:22.077016Z] Finished populate-webroot step (success) 03:23:22 INFO - [mozharness: 2016-11-29 11:23:22.077142Z] Running create-virtualenv step. 03:23:22 INFO - Running pre-action listener: _resource_record_pre_action 03:23:22 INFO - Running main action method: create_virtualenv 03:23:22 INFO - Creating virtualenv /builds/slave/test/build/venv 03:23:22 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 03:23:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:23:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:23:22 INFO - Using real prefix '/tools/python27' 03:23:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:23:22 INFO - Installing distribute.............................................................................................................................................................................................done. 03:23:25 INFO - Installing pip.................done. 03:23:25 INFO - Return code: 0 03:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:23:26 INFO - Reading from file tmpfile_stdout 03:23:26 INFO - Output received: 03:23:26 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) 03:23:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:23:26 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')]} 03:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:26 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')]} 03:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:26 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:26 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 03:23:26 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 03:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:26 INFO - 'HOME': '/Users/cltbld', 03:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:26 INFO - 'LOGNAME': 'cltbld', 03:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:26 INFO - 'MOZ_NO_REMOTE': '1', 03:23:26 INFO - 'NO_EM_RESTART': '1', 03:23:26 INFO - 'PAGER': '/bin/cat', 03:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:26 INFO - 'PWD': '/builds/slave/test', 03:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:26 INFO - 'SHELL': '/bin/bash', 03:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:26 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:26 INFO - 'USER': 'cltbld', 03:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:26 INFO - 'XPC_FLAGS': '0x0', 03:23:26 INFO - 'XPC_SERVICE_NAME': '0', 03:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:26 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 03:23:26 INFO - Cleaning up... 03:23:26 INFO - Return code: 0 03:23:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:23:26 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')]} 03:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:26 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')]} 03:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:26 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:26 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 03:23:26 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 03:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:26 INFO - 'HOME': '/Users/cltbld', 03:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:26 INFO - 'LOGNAME': 'cltbld', 03:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:26 INFO - 'MOZ_NO_REMOTE': '1', 03:23:26 INFO - 'NO_EM_RESTART': '1', 03:23:26 INFO - 'PAGER': '/bin/cat', 03:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:26 INFO - 'PWD': '/builds/slave/test', 03:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:26 INFO - 'SHELL': '/bin/bash', 03:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:26 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:26 INFO - 'USER': 'cltbld', 03:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:26 INFO - 'XPC_FLAGS': '0x0', 03:23:26 INFO - 'XPC_SERVICE_NAME': '0', 03:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:26 INFO - Downloading/unpacking psutil>=3.1.1 03:23:26 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 03:23:26 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 03:23:26 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 03:23:26 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 03:23:26 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 03:23:26 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 03:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:30 INFO - Installing collected packages: psutil 03:23:30 INFO - Running setup.py install for psutil 03:23:30 INFO - building 'psutil._psutil_osx' extension 03:23:30 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 03:23:30 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 03:23:30 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 03:23:30 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 03:23:30 INFO - building 'psutil._psutil_posix' extension 03:23:30 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 03:23:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:23:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:23:30 INFO - ^ 03:23:30 INFO - 1 warning generated. 03:23:30 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 03:23:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:30 INFO - Successfully installed psutil 03:23:30 INFO - Cleaning up... 03:23:30 INFO - Return code: 0 03:23:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:30 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:30 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 03:23:30 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 03:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:30 INFO - 'HOME': '/Users/cltbld', 03:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:30 INFO - 'LOGNAME': 'cltbld', 03:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:30 INFO - 'MOZ_NO_REMOTE': '1', 03:23:30 INFO - 'NO_EM_RESTART': '1', 03:23:30 INFO - 'PAGER': '/bin/cat', 03:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:30 INFO - 'PWD': '/builds/slave/test', 03:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:30 INFO - 'SHELL': '/bin/bash', 03:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:30 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:30 INFO - 'USER': 'cltbld', 03:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:30 INFO - 'XPC_FLAGS': '0x0', 03:23:30 INFO - 'XPC_SERVICE_NAME': '0', 03:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:23:31 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 03:23:31 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 03:23:31 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 03:23:31 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 03:23:31 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 03:23:31 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 03:23:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:23:33 INFO - Installing collected packages: mozsystemmonitor 03:23:33 INFO - Running setup.py install for mozsystemmonitor 03:23:33 INFO - Successfully installed mozsystemmonitor 03:23:33 INFO - Cleaning up... 03:23:33 INFO - Return code: 0 03:23:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:23:33 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')]} 03:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:33 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')]} 03:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:33 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:33 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 03:23:33 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 03:23:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:33 INFO - 'HOME': '/Users/cltbld', 03:23:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:33 INFO - 'LOGNAME': 'cltbld', 03:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:33 INFO - 'MOZ_NO_REMOTE': '1', 03:23:33 INFO - 'NO_EM_RESTART': '1', 03:23:33 INFO - 'PAGER': '/bin/cat', 03:23:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:33 INFO - 'PWD': '/builds/slave/test', 03:23:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:33 INFO - 'SHELL': '/bin/bash', 03:23:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:33 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:33 INFO - 'USER': 'cltbld', 03:23:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:33 INFO - 'XPC_FLAGS': '0x0', 03:23:33 INFO - 'XPC_SERVICE_NAME': '0', 03:23:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:34 INFO - Downloading/unpacking jsonschema==2.5.1 03:23:34 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 03:23:34 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 03:23:34 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 03:23:34 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 03:23:34 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 03:23:34 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 03:23:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:23:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:37 INFO - Downloading functools32-3.2.3-2.tar.gz 03:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:23:37 INFO - warning: no files found matching '*.txt' 03:23:37 INFO - no previously-included directories found matching 'build' 03:23:37 INFO - no previously-included directories found matching 'dist' 03:23:37 INFO - no previously-included directories found matching '.git*' 03:23:37 INFO - Installing collected packages: jsonschema, functools32 03:23:37 INFO - Running setup.py install for functools32 03:23:37 INFO - warning: no files found matching '*.txt' 03:23:37 INFO - no previously-included directories found matching 'build' 03:23:37 INFO - no previously-included directories found matching 'dist' 03:23:37 INFO - no previously-included directories found matching '.git*' 03:23:37 INFO - Successfully installed jsonschema functools32 03:23:37 INFO - Cleaning up... 03:23:37 INFO - Return code: 0 03:23:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:37 INFO - 'HOME': '/Users/cltbld', 03:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:37 INFO - 'LOGNAME': 'cltbld', 03:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:37 INFO - 'MOZ_NO_REMOTE': '1', 03:23:37 INFO - 'NO_EM_RESTART': '1', 03:23:37 INFO - 'PAGER': '/bin/cat', 03:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:37 INFO - 'PWD': '/builds/slave/test', 03:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:37 INFO - 'SHELL': '/bin/bash', 03:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:37 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:37 INFO - 'USER': 'cltbld', 03:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:37 INFO - 'XPC_FLAGS': '0x0', 03:23:37 INFO - 'XPC_SERVICE_NAME': '0', 03:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:23:37 INFO - Cleaning up... 03:23:37 INFO - Return code: 0 03:23:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:37 INFO - 'HOME': '/Users/cltbld', 03:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:37 INFO - 'LOGNAME': 'cltbld', 03:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:37 INFO - 'MOZ_NO_REMOTE': '1', 03:23:37 INFO - 'NO_EM_RESTART': '1', 03:23:37 INFO - 'PAGER': '/bin/cat', 03:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:37 INFO - 'PWD': '/builds/slave/test', 03:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:37 INFO - 'SHELL': '/bin/bash', 03:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:37 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:37 INFO - 'USER': 'cltbld', 03:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:37 INFO - 'XPC_FLAGS': '0x0', 03:23:37 INFO - 'XPC_SERVICE_NAME': '0', 03:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:23:38 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 03:23:38 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 03:23:38 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 03:23:38 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 03:23:38 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 03:23:38 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 03:23:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:23:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:23:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:23:41 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 03:23:41 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 03:23:41 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 03:23:41 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 03:23:41 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 03:23:41 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 03:23:41 INFO - Downloading docopt-0.6.1.tar.gz 03:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:23:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:23:41 INFO - Running setup.py install for blobuploader 03:23:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:23:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:23:42 INFO - Running setup.py install for requests 03:23:42 INFO - Running setup.py install for docopt 03:23:42 INFO - Successfully installed blobuploader requests docopt 03:23:42 INFO - Cleaning up... 03:23:42 INFO - Return code: 0 03:23:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:42 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')]} 03:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:42 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')]} 03:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:42 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:42 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 03:23:42 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 03:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:42 INFO - 'HOME': '/Users/cltbld', 03:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:42 INFO - 'LOGNAME': 'cltbld', 03:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:42 INFO - 'MOZ_NO_REMOTE': '1', 03:23:42 INFO - 'NO_EM_RESTART': '1', 03:23:42 INFO - 'PAGER': '/bin/cat', 03:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:42 INFO - 'PWD': '/builds/slave/test', 03:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:42 INFO - 'SHELL': '/bin/bash', 03:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:42 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:42 INFO - 'USER': 'cltbld', 03:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:42 INFO - 'XPC_FLAGS': '0x0', 03:23:42 INFO - 'XPC_SERVICE_NAME': '0', 03:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-sVuUEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-5HVUx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-cZseYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-NJvPVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-S0GdmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-5nTVXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-hgC1aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-zirpx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-oit1tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:23:43 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Q6yYuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-9W5Dld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-KFhCuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-bh4zsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-SjyeOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-TsGbdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-LoOj3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:23:44 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ZAv_G6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:23:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:23:44 INFO - Running setup.py install for manifestparser 03:23:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:23:44 INFO - Running setup.py install for mozcrash 03:23:45 INFO - Running setup.py install for mozdebug 03:23:45 INFO - Running setup.py install for mozdevice 03:23:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Running setup.py install for mozfile 03:23:45 INFO - Running setup.py install for mozhttpd 03:23:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Running setup.py install for mozinfo 03:23:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Running setup.py install for mozInstall 03:23:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:23:45 INFO - Running setup.py install for mozleak 03:23:46 INFO - Running setup.py install for mozlog 03:23:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Running setup.py install for moznetwork 03:23:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Running setup.py install for mozprocess 03:23:46 INFO - Running setup.py install for mozprofile 03:23:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Running setup.py install for mozrunner 03:23:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:23:46 INFO - Running setup.py install for mozscreenshot 03:23:47 INFO - Running setup.py install for moztest 03:23:47 INFO - Running setup.py install for mozversion 03:23:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:23:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:23:47 INFO - Cleaning up... 03:23:47 INFO - Return code: 0 03:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:47 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')]} 03:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:47 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')]} 03:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:47 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:47 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 03:23:47 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 03:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:47 INFO - 'HOME': '/Users/cltbld', 03:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:47 INFO - 'LOGNAME': 'cltbld', 03:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:47 INFO - 'MOZ_NO_REMOTE': '1', 03:23:47 INFO - 'NO_EM_RESTART': '1', 03:23:47 INFO - 'PAGER': '/bin/cat', 03:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:47 INFO - 'PWD': '/builds/slave/test', 03:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:47 INFO - 'SHELL': '/bin/bash', 03:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:47 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:47 INFO - 'USER': 'cltbld', 03:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:47 INFO - 'XPC_FLAGS': '0x0', 03:23:47 INFO - 'XPC_SERVICE_NAME': '0', 03:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:23:47 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-u7eVLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:23:47 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)) 03:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:23:47 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-8a4RuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:23:47 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)) 03:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:23:47 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-9PNn1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:23:47 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)) 03:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-tA3pTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-3wSDGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-IwmFlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-3n2HbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-_D4_K9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-YvJRxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-4BUFDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-DDorG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:23:48 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)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-8aqhc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:23:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-WwDp8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:23:49 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)) 03:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:23:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-vDo0S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:23:49 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)) 03:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-g4ziUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:49 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)) 03:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:23:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-diIQWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:23:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-9R5NKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:23:49 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)) 03:23:49 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)) 03:23:49 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)) 03:23:49 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)) 03:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:23:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:23:49 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 03:23:49 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 03:23:49 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 03:23:49 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 03:23:49 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 03:23:49 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 03:23:51 INFO - Downloading blessings-1.6.tar.gz 03:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:23:52 INFO - Installing collected packages: blessings 03:23:52 INFO - Running setup.py install for blessings 03:23:52 INFO - Successfully installed blessings 03:23:52 INFO - Cleaning up... 03:23:52 INFO - Return code: 0 03:23:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:23:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:52 INFO - Reading from file tmpfile_stdout 03:23:52 INFO - Current package versions: 03:23:52 INFO - blessings == 1.6 03:23:52 INFO - blobuploader == 1.2.4 03:23:52 INFO - docopt == 0.6.1 03:23:52 INFO - functools32 == 3.2.3-2 03:23:52 INFO - jsonschema == 2.5.1 03:23:52 INFO - manifestparser == 1.1 03:23:52 INFO - mozInstall == 1.12 03:23:52 INFO - mozcrash == 1.0 03:23:52 INFO - mozdebug == 0.1 03:23:52 INFO - mozdevice == 0.48 03:23:52 INFO - mozfile == 1.2 03:23:52 INFO - mozhttpd == 0.7 03:23:52 INFO - mozinfo == 0.9 03:23:52 INFO - mozleak == 0.1 03:23:52 INFO - mozlog == 3.4 03:23:52 INFO - moznetwork == 0.27 03:23:52 INFO - mozprocess == 0.23 03:23:52 INFO - mozprofile == 0.28 03:23:52 INFO - mozrunner == 6.13 03:23:52 INFO - mozscreenshot == 0.1 03:23:52 INFO - mozsystemmonitor == 0.3 03:23:52 INFO - moztest == 0.7 03:23:52 INFO - mozversion == 1.4 03:23:52 INFO - psutil == 3.1.1 03:23:52 INFO - requests == 1.2.3 03:23:52 INFO - wsgiref == 0.1.2 03:23:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:52 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:52 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 03:23:52 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 03:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:52 INFO - 'HOME': '/Users/cltbld', 03:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:52 INFO - 'LOGNAME': 'cltbld', 03:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:52 INFO - 'MOZ_NO_REMOTE': '1', 03:23:52 INFO - 'NO_EM_RESTART': '1', 03:23:52 INFO - 'PAGER': '/bin/cat', 03:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:52 INFO - 'PWD': '/builds/slave/test', 03:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:53 INFO - 'SHELL': '/bin/bash', 03:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:53 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:53 INFO - 'USER': 'cltbld', 03:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:53 INFO - 'XPC_FLAGS': '0x0', 03:23:53 INFO - 'XPC_SERVICE_NAME': '0', 03:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 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)) 03:23:53 INFO - Cleaning up... 03:23:53 INFO - Return code: 0 03:23:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:23:53 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')]} 03:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:53 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')]} 03:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:53 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 0x10e86c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe134b87c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, '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.Tki30VSpXw/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.83iJ9ahFD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 03:23:53 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 03:23:53 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 03:23:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:23:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:53 INFO - 'HOME': '/Users/cltbld', 03:23:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:53 INFO - 'LOGNAME': 'cltbld', 03:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:53 INFO - 'MOZ_NO_REMOTE': '1', 03:23:53 INFO - 'NO_EM_RESTART': '1', 03:23:53 INFO - 'PAGER': '/bin/cat', 03:23:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:53 INFO - 'PWD': '/builds/slave/test', 03:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:53 INFO - 'SHELL': '/bin/bash', 03:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:23:53 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:53 INFO - 'USER': 'cltbld', 03:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:53 INFO - 'XPC_FLAGS': '0x0', 03:23:53 INFO - 'XPC_SERVICE_NAME': '0', 03:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:23:53 INFO - Cleaning up... 03:23:53 INFO - Return code: 0 03:23:53 INFO - Running post-action listener: _resource_record_post_action 03:23:53 INFO - Running post-action listener: _start_resource_monitoring 03:23:53 INFO - Starting resource monitoring. 03:23:53 INFO - [mozharness: 2016-11-29 11:23:53.560138Z] Finished create-virtualenv step (success) 03:23:53 INFO - [mozharness: 2016-11-29 11:23:53.561141Z] Running install step. 03:23:53 INFO - Running pre-action listener: _resource_record_pre_action 03:23:53 INFO - Running main action method: install 03:23:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:53 INFO - Reading from file tmpfile_stdout 03:23:53 INFO - Detecting whether we're running mozinstall >=1.0... 03:23:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:23:53 INFO - Reading from file tmpfile_stdout 03:23:53 INFO - Output received: 03:23:53 INFO - Usage: mozinstall [options] installer 03:23:53 INFO - Options: 03:23:53 INFO - -h, --help show this help message and exit 03:23:53 INFO - -d DEST, --destination=DEST 03:23:53 INFO - Directory to install application into. [default: 03:23:53 INFO - "/builds/slave/test"] 03:23:53 INFO - --app=APP Application being installed. [default: firefox] 03:23:53 INFO - mkdir: /builds/slave/test/build/application 03:23:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:24:24 INFO - Reading from file tmpfile_stdout 03:24:24 INFO - Output received: 03:24:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:24:24 INFO - Running post-action listener: _resource_record_post_action 03:24:24 INFO - [mozharness: 2016-11-29 11:24:24.864588Z] Finished install step (success) 03:24:24 INFO - [mozharness: 2016-11-29 11:24:24.864758Z] Running run-tests step. 03:24:24 INFO - Running pre-action listener: _resource_record_pre_action 03:24:24 INFO - Running main action method: run_tests 03:24:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:24 INFO - 2016-11-29 03:24:24.879 screenresolution[1902:15908] starting screenresolution argv=screenresolution get 03:24:24 INFO - 2016-11-29 03:24:24.882 screenresolution[1902:15908] Display 0: 1600x1200x32@60 03:24:24 INFO - 2016-11-29 03:24:24.887 screenresolution[1903:15910] starting screenresolution argv=screenresolution list 03:24:24 INFO - Available Modes on Display 0 03:24:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:25 INFO - Intel Iris: 03:24:25 INFO - Chipset Model: Intel Iris 03:24:25 INFO - Type: GPU 03:24:25 INFO - Bus: Built-In 03:24:25 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:25 INFO - Vendor: Intel (0x8086) 03:24:25 INFO - Device ID: 0x0a2e 03:24:25 INFO - Revision ID: 0x0009 03:24:25 INFO - Displays: 03:24:25 INFO - Display: 03:24:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:25 INFO - Display Serial Number: 1600x1200 60 03:24:25 INFO - Main Display: Yes 03:24:25 INFO - Mirror: Off 03:24:25 INFO - Online: Yes 03:24:25 INFO - Rotation: Supported 03:24:25 INFO - Return code: 0 03:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:24:25 INFO - Python 2.7.3 03:24:25 INFO - Return code: 0 03:24:25 INFO - grabbing minidump binary from tooltool 03:24: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')]} 03:24:25 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 0x10e680d30>, '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 0x10e4e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e678b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:24:25 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 03:24:25 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 03:24:25 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 03:24:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:24:25 INFO - Return code: 0 03:24:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:24:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:24:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:24:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:24:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 03:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0241 --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 03:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tki30VSpXw/Render', 03:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:25 INFO - 'HOME': '/Users/cltbld', 03:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:25 INFO - 'LOGNAME': 'cltbld', 03:24:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:24:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:25 INFO - 'MOZ_NO_REMOTE': '1', 03:24:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:24:25 INFO - 'NO_EM_RESTART': '1', 03:24:25 INFO - 'PAGER': '/bin/cat', 03:24:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:25 INFO - 'PWD': '/builds/slave/test', 03:24:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:25 INFO - 'SHELL': '/bin/bash', 03:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83iJ9ahFD7/Listeners', 03:24:25 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 03:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:25 INFO - 'USER': 'cltbld', 03:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:25 INFO - 'XPC_FLAGS': '0x0', 03:24:25 INFO - 'XPC_SERVICE_NAME': '0', 03:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 03:24:25 INFO - mozversion application_buildid: 20161129010327 03:24:25 INFO - mozversion application_changeset: 94dac74c74a6e462fbbf9a1e1cad9fda3024122e 03:24:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:24:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:24:25 INFO - mozversion application_name: Firefox 03:24:25 INFO - mozversion application_remotingname: firefox-dev 03:24:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:25 INFO - mozversion application_vendor: Mozilla 03:24:25 INFO - mozversion application_version: 52.0a2 03:24:25 INFO - mozversion platform_buildid: 20161129010327 03:24:25 INFO - mozversion platform_changeset: 94dac74c74a6e462fbbf9a1e1cad9fda3024122e 03:24:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:25 INFO - mozversion platform_version: 52.0a2 03:24:25 INFO - using testdate: 1480418665 03:24:25 INFO - actual date: 1480418665 03:24:25 INFO - starting webserver on 'localhost:50850' 03:24:25 INFO - SUITE-START | Running 2 tests 03:24:25 INFO - TEST-START | dromaeo_css 03:24:25 INFO - operating with platform_type : mac_ 03:24:25 INFO - Initialising browser for dromaeo_css test... 03:24:25 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp2qH9li/profile http://localhost:50850/getInfo.html) 03:24:28 INFO - PROCESS | 1913 | 2016-11-29 03:24:28.432 plugin-container[1915:16086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 03:24:28 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:28 INFO - PROCESS | 1913 | 2016-11-29 03:24:28.435 plugin-container[1915:16086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 03:24:28 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:28 INFO - PROCESS | 1913 | 2016-11-29 03:24:28.435 plugin-container[1915:16086] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:24:28 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 03:24:28 INFO - PROCESS | 1913 | colorDepth:24 03:24:28 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 03:24:28 INFO - PROCESS | 1913 | __metrics 03:24:28 ERROR - PROCESS | 1913 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:24:29 INFO - TEST-INFO | 1913: exit 0 03:24:29 INFO - Browser initialized. 03:24:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:24:29 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmp2qH9li/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:32 INFO - PROCESS | 1916 | 2016-11-29 03:24:32.067 plugin-container[1917:16214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 03:24:32 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:32 INFO - PROCESS | 1916 | 2016-11-29 03:24:32.067 plugin-container[1917:16214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 03:24:32 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:32 INFO - PROCESS | 1916 | 2016-11-29 03:24:32.067 plugin-container[1917:16214] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:24:41 INFO - PROCESS | 1916 | RSS: Main: 230432768 03:24:41 INFO - PROCESS | 1916 | 03:25:31 INFO - PROCESS | 1916 | 1480418731371 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:27:45 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50850/tests/dromaeo/cssquery-ext.html) 03:27:47 INFO - PROCESS | 1916 | RSS: Main: 233078784 03:27:47 INFO - PROCESS | 1916 | 03:30:49 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-ext.html (next: http://localhost:50850/tests/dromaeo/cssquery-jquery.html) 03:30:50 INFO - PROCESS | 1916 | RSS: Main: 243982336 03:30:50 INFO - PROCESS | 1916 | 03:33:51 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50850/tests/dromaeo/cssquery-mootools.html) 03:33:52 INFO - PROCESS | 1916 | RSS: Main: 247123968 03:33:52 INFO - PROCESS | 1916 | 03:36:54 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50850/tests/dromaeo/cssquery-prototype.html) 03:36:55 INFO - PROCESS | 1916 | RSS: Main: 246382592 03:36:55 INFO - PROCESS | 1916 | 03:39:57 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50850/tests/dromaeo/cssquery-yui.html) 03:39:58 INFO - PROCESS | 1916 | RSS: Main: 249749504 03:39:58 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:50850/tests/dromaeo/cssquery-yui.html (next: http://localhost:50850/tests/dromaeo/cssquery-dojo.html) 03:43:01 INFO - PROCESS | 1916 | RSS: Main: 251244544 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | __start_tp_report 03:43:01 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 03:43:01 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 03:43:01 INFO - PROCESS | 1916 | |i|pagename|runs| 03:43:01 INFO - PROCESS | 1916 | |0;dojo.html;2880.6975267596904;3186.2193762528177;3362.3107263011084;3383.4924761285806;3401.285729996699;5703.828885133442;5728.598998070137;5753.683547404897;5806.651600903938;5825.475707186352;5725.770969161229;5844.298684157897;5864.5894787364905;5875.265591801025;5888.941110588893;5202.0376230397405;5221.086309895772;5224.346956630421;5241.371035475749;5317.574594032488;5259.6055295852775;5288.92066619001;5301.496357846017;5309.4425085365865;5322.121849894763;5197.324347834776;5295.576353891699;5306.257124002643;5306.495882891119;5353.384360798519;5161.2516185152945;5183.844484665447;5227.947720522784;5261.289725886994;5292.941411717633;5161.870953226162;5167.173252279655;5198.298229738974;5232.790688372461;5276.551493123089;5572.136318870582;5768.077107662796;5804;5819.912701309441;5850.76596936122;6150.50795936324;6178.783742569033;6183.474404675608;6214.347493513161;6263.154474146023;6264.9686751566405;6311.400416960381;6313;6323.177986861724;6347.174867267226;5742.799002034993;5903.2325797646035;5925.4370834770625;5938.940610593925;5947.405259474018;5200.7139607321205;6249.312575616678;6274.309825919146;6286.308506064329;6303.2751233607805;6164.075388691732;6179.042248451407;6188.690565471708;6191.504679625619;6208.068789681584;6113.141862489121;6170.228721409824;6194.5973511721595;6263.96868015657;6284.685765711696;6207.6275423474735;6213;6257.499400047986;6278.246610406781;6295.087212354275;6271.247450305992;6286.214223222098;6286.874262514815;6294.402031806971;6326.082718005863;4797;4905.484924082992;4935.629827762932;4970.751462426936;5006.574441172541;4397.516273210008;4428.180786554498;4450.06548624786;4470;4555.2483840166005;4538.04701012791;5883.499902508249;5915.852103697442;5989.401059893976;5990.64056156632;6172.321044685081;6295.905561416583;6303.558750887394;6305.684715764193;6327;5719.399463056402;5762.366139724627;5814.970925145347;5882.705864706748;5902.734376953047;5342.011727830286;5357.973210133924;5380.139177731544;5400.351957765093;5410.107332290207;6288.874222515524;6303;6320;6335.36646335372;6345.841353966188;6120.724567394389;6249.562530622813;6253.96873015632;6287.1512345832725;6295.276043255084;6233.875322493434;6288.339724328879;6296.275928268308;6307.148534947723;6325.209348831396;6256.718447669775;6258.342873998165;6280.309165991828;6283.497320214463;6287.30839607652;6123.989541725566;6164.229471316085;6250.187475628321;6319.747210111728;6345.1434056401795;6092.664903430354;6110.042400473335;6133.815985520441;6150.815475535741;6165.414285642857;6179;6191.9690401547705;6194.876102478015;6200.193974783249;6215.720292586935;5905.822825315247;6058.363871793398;6095.9390406097145;6110.480609148272;6138.815835524942;5997.640141591519;6117.480014198843;6134.263888333428;6165.229346331708;6204.3795620437595;5919;5998.100284957117;6026.337102918763;6072.544559158169;6138.539609529393 03:43:01 INFO - PROCESS | 1916 | |1;ext.html;494.33018260257353;507.9517445842648;510.3263691926656;517.6376536424501;526.7866514061807;35957;39710.82313414931;39722.40416393753;41699;41737.373939390935;32392.19019524507;40778.7961060195;41186.58813411865;41207.17585648285;41408;7414.406847452191;7887.369010479148;9303.767405814875;9469.81060378792;9473.19477844384;9338.439693618371;9373.718788436323;9468.952655236713;9485.762855928588;9492.905070949306;9479.289053320994;9480.431174129575;9519.095685909828;9523.904760952371;9535.618575256982;7936.4047696423;9418.81162376752;9435.962044175136;9478.999999999965;9500.809983800285;9265;9338.019507951634;9340.906590934072;9345.813083738356;9351.064893510662;38939.80530097331;38950.220995579926;39232.80383598092;39295.41056884149;39341.803290983364;3415.2486452980306;3448.189675426298;3531.964680353189;3562.3943929532043;3683.9869036015043;29194.562081568794;29371;29663.110106696833;30216.8489157555;30236.395272094655;3076.2928622812005;3160.5891234139413;3164.620245570546;3177.8887738929025;3188.984055079733;459030;466170;468609.99999999657;469570;470183;61000;61568;61616.075758863655;61677;61761.76476470535;62954.37045629485;64255.71488570296;64351;64602.353976460574;64660.383490413114;15892.284847181905;16168;16257.837421625869;16391.180440977903;16392.098434585972;12454.816792404707;12491.750164996649;12509.124361294804;12563.18339307942;12607.621771346874;17424.825751742574;18492.445226643224;18536.351227706964;18593.34923277679;18647.44057678272;7483.214262502468;7605.961970190114;7675.7718764997335;7705.075390953009;7735.381169506426;775.3680750188691;897.3987428422972;927.5501381829807;946.5030858799048;982.0964712464549;29590.112296631134;29975.602983179735;30455.390892182033;31235.375292494024;31663.683363166074;929.4330458420358;996.8504724291414;1049.480507148951;1075.2043487818958;1126.549380247891;13644.113132646546;15209.847901521065;15222.086674799548;15265.465708700141;15388.61528461775;608784;673014;682673;683466;683664;54666;55050;55052;55077.44922550723;55189.72405138029;1338.8795008449285;1342.3690865293297;1372.4647387518676;1379.8689124533153;1386.4454218312756;26532.806023729365;28156.71843281541;28744.425111498073;28968.84124634991;29557.408851823275;45416;49531;49559.00881982412;49603.50396495989;49692;597106;597543;604112;604443;605089;25550.488990220463;25998.87000564985;26894.731052690004;26938;27757.58363624507;45632;45687;45966.540334596226;46169.76915115403;46202;37176.07059823526;37319.81340093282;37926;39048.6095139045;39346;29513;32936;37534.8123259382;37684;38780.41829372618;28789;35504.82247588746;36709;36885.81557092197;37277.25445491021;32361.35277294488;33617.66382336145;34427.82786086053;34428.31143377168;34472.482912755826;22713.88643056774;25263.747362526876;25471.745282546937;25884;25984.51565178734 03:43:01 INFO - PROCESS | 1916 | |2;jquery.html;319.77775446064993;366.8789299531155;368.9520362352895;378.134072972892;397.3245482679444;18823.247070117195;19528.414147575597;19613.41159765206;20198.69701954468;20300;19607.607847843035;19772.90113549434;20198.980101989793;20376.898115509437;20571;5294.1529355303155;5534.612577119603;5784.82645520635;6064.666443345614;6077.604955677867;5848.210491583645;6049.758009679629;6050.576459647827;6052.273727152748;6065.211522502069;5668.348139963916;5880.911786323209;5935.49548288396;5990.760369585211;6022.759089636431;5840.06558950568;5891.351951285355;5913.349531551526;5920.993431116721;5926.4073592640825;5037.4458809530925;5061.645684802065;5199.220116982445;5202;5222.16445368743;24450.877745611335;24705.382365441008;24828.751712482826;24851.378715532257;25070.874645626838;2426.9393265168337;2462.175171317611;2907.447584958872;2975.8958436454836;3062.173213232437;14856.480023199245;14907.329170187368;15049.924750376178;15718.607034824106;15886.841131588651;1829.1128802530761;1860.692985657365;1918.9712154317697;1922.7404300419405;1952.9023548822645;112928;115421.42289288584;115438;115760;116023;10488.05607495329;10649.361038337725;10892.128629709605;11007.284526505751;11095.44522773858;13558.322083895962;13559.050866439255;13589.252591107585;13591.86408135906;13618.387172577257;8132.471389359673;8453.239208471266;8474.915250847536;8580.399372043898;8580.485170889766;8476.321894248445;8508.489490630582;8545.957270213734;8559.529225892511;8622.482651040958;6380.097055839582;6560.409563139245;6573.934260657428;6588.143541339595;6688.933110668831;1828.9817101828812;1856.7214917762444;1856.7957524672274;1859.6280743851285;1864.4686264414577;740.4668638580214;837.9790505237418;924.4638109896244;1000.1998401278947;1021.1473419694489;21275.085242328263;21349;21420.892895535424;21484.462888427915;21720.022598983083;930.8277968575835;953.1326492891435;959.6545243712258;963.643451922793;965.9806803863884;10165.69502914899;11013.403056556754;11990.280583165038;12004.819927701092;12017.377654016596;629919;630158;646842;665520;667302;102082;127754.36122819512;135839;136134;142081;945.5414124149781;988.3279370028306;994.8308787506141;1001.5993602558889;1003.307717674802;18011.099445027958;18890.905545472186;19199.32802351911;19255.903720481307;19284.903575482032;54223.915521688556;55549.722251388484;56136.24130300426;57568.71215643895;58434;75224.62387688027;77198;78913.60543197476;80157.59921200357;83495.58252208943;10207.438590877573;10803.75756787948;11660.242084264495;12115.697107572381;12404.875951240372;12517.061220408688;13317.66705832362;13346.799798002843;13552.457901683822;13766.65583360424;10683.626073087406;10711;10737.785244294913;10768.784624307313;11115;7573.46985710995;7697.307242348215;11247.156463265124;11314.15143864197;11359.977602015859;7915.802104947192;7949.483283586548;8016.599170041592;8089.9191008089165;8113.959430202811;11655.067594592243;12244.367460995929;12261.080418968791;12312.753744924872;12343.69140771452;11176.664700059011;11212.43937803123;11228.775424491301;11253.7749245013;11453.31280123162 03:43:01 INFO - PROCESS | 1916 | |3;mootools.html;1448.5726710620365;1487.9107253564787;1626.6258760485089;1712.195268223936;1739.260814153985;7511.511751736133;7763.107242667095;8013.879791803128;8395.958020209906;8397.790055248613;7895.289423951843;8138.0641226258995;8173.67305307787;8225.547594882279;8274.62764175612;2288.3706980580305;2312.6762253284555;2315.780000899912;2319.2346525646494;2327.3948773318975;2214.191819985702;2242.237639202671;2259.3222033389998;2266.456050547873;2277.430642339415;2234.441389652587;2237.7314722233355;2239.6864438978555;2292.919747808827;2320.5010922651627;1703.855172310349;1880.3888736160734;1894.4032629721594;1903.8671990165828;2247.0899285789283;1850.917213430145;2286.2341115726254;2330.6154484510034;2332.5684748321446;2332.918347857834;7145.92854071463;8222.218889205516;8366.916330836675;8372.916270837275;8380.20388063133;4447.332900064984;5022.196448568248;5191.792328306863;5197.324347834795;5199.168133098724;3276.7050965413227;3296.4890441981292;3434.364642541113;3460.723142148622;3514.9824250878837;1021.1167340250704;1074.7152004718755;1079.02348374721;1087.141158484796;1093.191038631415;10677.25259231854;10719.356838589709;10737.926207379278;10819.621313253998;10849.294795838245;4485.977570112129;4604.815807367668;4684.5549672781035;4723.669343146015;4731.95268047322;9317.44095354281;9358.204552612968;9424.246060315296;9435.811283774286;9444;5094.032133894548;5142.97428512855;5191.5846732261325;5193.870153246124;5199.584033277329;3611.3138503684218;3710.6474884885893;4420.005498762817;4661.370714953506;4757.500462451393;3773.1699026214187;3780.3006443807985;3835.5972622874756;3838.673712734449;3899.7075219358094;1067.962621308266;1262.4129779652505;1271.4659842865833;1274.7004453953314;1279.1046267612708;866.9089745576749;869.7216890595075;870.1776820904184;892.1791951404723;892.821435712847;5601.467860553241;5602.551795856403;5779.190913272145;5811.215485909433;5903.822885313448;815.6981916690744;891.9519564511672;907.3285768531372;908.5139450394007;959.5538074795254;3075.308055687497;3702.3520883845217;3703.7222208334483;3768.095657042345;3819.9427008594894;9478;10015.248856335804;10044.949775251076;10049.497525123861;10063.5471403787;9884.406935583887;9886.159676427584;9898.010198980044;9986.900130998742;10006.69979900604;7608.657610407545;7650.42621803367;7700.730474433368;7816.765497035098;7859.646315915683;5262.421133675216;5302.707786743139;5359.758810853443;5408;5486.807961721321;9270.814583708423;9322.720318390187;9418.529073546433;9431.669891553762;9436.52817359143;7276.163241227327;7666.501677390952;8351.874721879298;9560.56977436031;9567.61729530811;6002.789902353396;6546.443552298108;6572.638504882086;6646;6717.395434410926;7219.422446204186;7824.334931530883;7837.960810195913;7867;7895.171007044178;5448.918266226089;5914.467697907209;6705.832354191184;7137.714491420493;7208.89186662211;6199.814005579839;6809.829754255985;6933.722651094102;7005.299470052954;7121.287871212838;6597.835054123685;6626.701798418986;6627.271000190071;6686.197656281277;6695.4308883745425;4090.836366545305;4621.445426548836;4681.578657920803;4827.975860120818;4933.9013219736125;4748.05038992196;4758.547937945889;4794.424669039738;4870;4890.853274401774 03:43:01 INFO - PROCESS | 1916 | |4;prototype.html;285.86369182499567;309.6995913963455;310.09916193458014;310.15328154139183;318.5444813916101;4593.678442509022;5306.257124002643;5487;5498.367687715914;5553.8898216998505;5361.169018802092;5373.361124856917;5380.623356365056;5426.864328391792;5437.184422336642;3871.9225615487812;3953.9011524711896;4083.3462447708725;4120.876373708778;4144.979275103636;3051.5575241589954;3631.455281707752;4025.409963064588;4150.730202536825;4163;3335.783174093688;3646.762960407565;4145.730527515701;4166.625003749662;4181.393697913801;3786.9775160706595;3965.0174664916003;4108.26051310764;4147.688923330732;4180.393842892779;3576.9269219234384;3594.6045934947133;3595.2090540081404;3613.7470377073614;3616.620254873253;4048.9430479987714;4362.803673834654;4453.933191002106;4915.967646794142;5276.894462110775;4051.8379264829373;4262.65898728101;4603.861884143447;4730.195866702668;4772.856814295542;4916.778744956486;5709.97446800005;5736.569757268222;5738.885222295572;5755.30936287644;3594.820258987066;3628.6915612172997;3900.980495097535;4000.959510649561;4011.452727518018;4735.763211839394;5806.854828629276;5973.611715238539;5973.761049558012;6049.818505444844;3472.7569070164845;3511.2099777549847;3803.7717736935874;4038.4749982502676;4091.2226676931286;4078.817143028548;4126.525449573277;4127.938080928789;4135.069609337875;4136.896577585524;3644.835982380799;3786.2772438540946;4672.2057250267535;4715.147335861754;4741.336212930192;5006.3992320921725;5054.635248482962;5553.195636203772;5635.802746903839;5777.913331300034;1316.8288122544009;1337.458329376604;1337.6789570503008;1338.598420473854;1342.3489607540334;752.5183882315323;850.7362717557577;873.4322690251388;907.9364444488955;909.5225006871445;4812.807487700523;5190.6885586864155;5266.235226506038;5332.454247582471;5455.972720136453;4802.807887684523;5495.900819835969;5912.497437717756;5944.740998420572;5990.281166260077;4759.809607615726;5476.659237864899;5487.807926722546;5490.588205884575;5504.394516603171;2493.7905116018715;2542.987285063563;2695.7759848125374;2884.298264024194;2991.491446454108;5822.9518686635965;5849.88300233993;5851.473367396955;5901.498372638288;5921;5368.8389348319615;5444.91832622511;5785.342799046808;5794.82615521535;5819.301683797971;4955.934474088089;5300.561741598831;5821.359650438449;5856.736446859816;5895.734691938873;4939.653664509329;5355;5665.405132461032;5686.9715651421475;5698.2592263005545;4510.564295280512;4519.932201017054;4961.7519124044375;5036.765992331902;5620.831375058591;4955.069705642908;5087.870959359893;5593.860153496195;5709.743061562157;5826.785821388015;3504.456809194579;3904.39481880309;3905.2941205847455;3919.7844118573753;3928.4107383892647;5616.269884914935;5624.74688639004;5656.5959467227085;5663.377028526941;5676.33212390176;4862.70823750562;5460.771326451517;5535.944640553543;5574.526165275997;5602.52378547828;5234.80138341905;5267.7366131694025;5346.25152478661;5432;5507.531859791802;4294.334378171388;4724.952750472451;5003.3495645565845;5339.997500937148;5347.224652425454;4256.489221293465;5140.43455219933;5300.80731835334;5478.01395748769;5672.347680016685;4329.502107257706;5589.965856316594;5591.021571225101;5627.718614069362;5678.5457163426 03:43:01 INFO - PROCESS | 1916 | |5;yui.html;685.6160550091948;706.2443185791204;741.6662501874158;760.6196901549225;788.4874831359617;11443.485043173056;13618.591442256737;14378.92158088145;14512.984091113627;14567.562973110811;10896.782064358707;11019.338839669625;11252.606158784443;12499.562515311965;12546.059045571596;640.6540468147207;665.7836203233944;673.865226954611;799.7041094794934;832.142892820393;813.515958004986;829.3986859526855;829.4484168028254;829.8257365953157;836.1638361638362;635.8855406026913;857.995710021449;870.4820631724145;878.2227728460294;879.0725784297564;870.5299138465222;872.8123453457505;877.0659247900965;878.5299864572455;878.8330217258732;655.3119224813973;655.9573627714183;656.4977791989132;656.7471523463453;657.1982181738272;13298.202107873558;13495.797563036562;13592.86407135926;13596.252206128658;14180.50368237117;10094.394336339843;12801.679958001032;12838;13137.474501019948;13640.863591364157;11676.1826672133;12004.459799309052;12047.638570842888;12157.817632735516;12334.136610437274;4367.213901497729;4462.263726485126;4472.709273897186;4483.932741008887;4624.861254162381;85367.57316213442;85744;86984;87929.68105478423;90634.5468272661;9853.50732463374;10013.949930250303;10038.146757525692;10041.648542300984;10108.090271875566;17856.821431785775;17895.37366192177;17916.283348665907;17924.641507169785;18322.541936451697;7568.962155189189;7569.810754731176;7595.658195381111;7661.961690191625;7734;4104.384342348671;4181.393697913785;4248.022954720432;4248.532661407243;4301.397804307399;3172.7303179230025;3221.0336898930227;3252.5121231815415;3278.131295206772;3293.193167673935;622.6544267931341;628.9371062893674;629.2857606616524;630.1052505442192;639.5107742576839;588.4439204951366;591.2373038780012;592.846912754688;593.3739904400899;630.6594439002887;7952;8362.665493380318;8629.266512346396;8650.6972255972;8888.422252553562;497.4478329054746;523.2047288122034;543.0794802809279;563.8703098287417;602.6685323072293;4154.563770804082;4309.28896732042;4400.405945197422;4531.524189960007;4637.97964447821;60539;69800.3019969804;93040.53479732694;105689;108012;88270;90530;92611;93739.06260937304;94516.52741736385;58999.705001474715;72240.63879680568;75789.24210757822;76547;85858.57070714732;9448.858267126136;9478.894145564143;9682.273829462678;9755.902440975784;9756.51217439111;51209.97580048304;51496.48503514917;52075.47924520706;56256.43743562676;59756.72554381901;62428.68785656225;78648.82026769489;79506;79541.60229198817;81856.59071704603;11806.645800625995;12187.939060304641;12199.817002745143;12282;12397.318147501974;14150.504732334319;14241.21673307978;14686.55940321792;14870.553883383516;14915.776263355841;13065.869341306465;13168.209907405586;13313.800292995418;13362;13854.515091971733;10189.796204076025;11494.712632184262;12998.415071322002;13058.869411305765;13218.60344189676;11470.19708620422;12317.199382040139;12581.87418125807;12632;13026;10492.895071049192;10592.576296948344;10672.83990740124;10701.839472408075;10980.890191098306;9517.381370210915;11064.834027489433;11166.218364714392;11242.83135752948;11265.605703800327 03:43:01 INFO - PROCESS | 1916 | __end_tp_report 03:43:01 INFO - PROCESS | 1916 | __start_cc_report 03:43:01 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,367 03:43:01 INFO - PROCESS | 1916 | __end_cc_report 03:43:01 INFO - PROCESS | 1916 | __startTimestamp1480419781436__endTimestamp 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | ------- Summary: start ------- 03:43:01 INFO - PROCESS | 1916 | Number of tests: 6 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5778.80 Median:6072.54 stddev:665.51 (11.0%) stddev-sans-first:627.52 03:43:01 INFO - PROCESS | 1916 | Values: 2880.7 3186.2 3362.3 3383.5 3401.3 5703.8 5728.6 5753.7 5806.7 5825.5 5725.8 5844.3 5864.6 5875.3 5888.9 5202.0 5221.1 5224.3 5241.4 5317.6 5259.6 5288.9 5301.5 5309.4 5322.1 5197.3 5295.6 5306.3 5306.5 5353.4 5161.3 5183.8 5227.9 5261.3 5292.9 5161.9 5167.2 5198.3 5232.8 5276.6 5572.1 5768.1 5804.0 5819.9 5850.8 6150.5 6178.8 6183.5 6214.3 6263.2 6265.0 6311.4 6313.0 6323.2 6347.2 5742.8 5903.2 5925.4 5938.9 5947.4 5200.7 6249.3 6274.3 6286.3 6303.3 6164.1 6179.0 6188.7 6191.5 6208.1 6113.1 6170.2 6194.6 6264.0 6284.7 6207.6 6213.0 6257.5 6278.2 6295.1 6271.2 6286.2 6286.9 6294.4 6326.1 4797.0 4905.5 4935.6 4970.8 5006.6 4397.5 4428.2 4450.1 4470.0 4555.2 4538.0 5883.5 5915.9 5989.4 5990.6 6172.3 6295.9 6303.6 6305.7 6327.0 5719.4 5762.4 5815.0 5882.7 5902.7 5342.0 5358.0 5380.1 5400.4 5410.1 6288.9 6303.0 6320.0 6335.4 6345.8 6120.7 6249.6 6254.0 6287.2 6295.3 6233.9 6288.3 6296.3 6307.1 6325.2 6256.7 6258.3 6280.3 6283.5 6287.3 6124.0 6164.2 6250.2 6319.7 6345.1 6092.7 6110.0 6133.8 6150.8 6165.4 6179.0 6192.0 6194.9 6200.2 6215.7 5905.8 6058.4 6095.9 6110.5 6138.8 5997.6 6117.5 6134.3 6165.2 6204.4 5919.0 5998.1 6026.3 6072.5 6138.5 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:70484.58 Median:39716.61 stddev:156469.32 (394.0%) stddev-sans-first:156820.02 03:43:01 INFO - PROCESS | 1916 | Values: 494.3 508.0 510.3 517.6 526.8 35957.0 39710.8 39722.4 41699.0 41737.4 32392.2 40778.8 41186.6 41207.2 41408.0 7414.4 7887.4 9303.8 9469.8 9473.2 9338.4 9373.7 9469.0 9485.8 9492.9 9479.3 9480.4 9519.1 9523.9 9535.6 7936.4 9418.8 9436.0 9479.0 9500.8 9265.0 9338.0 9340.9 9345.8 9351.1 38939.8 38950.2 39232.8 39295.4 39341.8 3415.2 3448.2 3532.0 3562.4 3684.0 29194.6 29371.0 29663.1 30216.8 30236.4 3076.3 3160.6 3164.6 3177.9 3189.0 459030.0 466170.0 468610.0 469570.0 470183.0 61000.0 61568.0 61616.1 61677.0 61761.8 62954.4 64255.7 64351.0 64602.4 64660.4 15892.3 16168.0 16257.8 16391.2 16392.1 12454.8 12491.8 12509.1 12563.2 12607.6 17424.8 18492.4 18536.4 18593.3 18647.4 7483.2 7606.0 7675.8 7705.1 7735.4 775.4 897.4 927.6 946.5 982.1 29590.1 29975.6 30455.4 31235.4 31663.7 929.4 996.9 1049.5 1075.2 1126.5 13644.1 15209.8 15222.1 15265.5 15388.6 608784.0 673014.0 682673.0 683466.0 683664.0 54666.0 55050.0 55052.0 55077.4 55189.7 1338.9 1342.4 1372.5 1379.9 1386.4 26532.8 28156.7 28744.4 28968.8 29557.4 45416.0 49531.0 49559.0 49603.5 49692.0 597106.0 597543.0 604112.0 604443.0 605089.0 25550.5 25998.9 26894.7 26938.0 27757.6 45632.0 45687.0 45966.5 46169.8 46202.0 37176.1 37319.8 37926.0 39048.6 39346.0 29513.0 32936.0 37534.8 37684.0 38780.4 28789.0 35504.8 36709.0 36885.8 37277.3 32361.4 33617.7 34427.8 34428.3 34472.5 22713.9 25263.7 25471.7 25884.0 25984.5 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:36725.50 Median:21602.24 stddev:107684.96 (498.5%) stddev-sans-first:107952.35 03:43:01 INFO - PROCESS | 1916 | Values: 319.8 366.9 369.0 378.1 397.3 18823.2 19528.4 19613.4 20198.7 20300.0 19607.6 19772.9 20199.0 20376.9 20571.0 5294.2 5534.6 5784.8 6064.7 6077.6 5848.2 6049.8 6050.6 6052.3 6065.2 5668.3 5880.9 5935.5 5990.8 6022.8 5840.1 5891.4 5913.3 5921.0 5926.4 5037.4 5061.6 5199.2 5202.0 5222.2 24450.9 24705.4 24828.8 24851.4 25070.9 2426.9 2462.2 2907.4 2975.9 3062.2 14856.5 14907.3 15049.9 15718.6 15886.8 1829.1 1860.7 1919.0 1922.7 1952.9 112928.0 115421.4 115438.0 115760.0 116023.0 10488.1 10649.4 10892.1 11007.3 11095.4 13558.3 13559.1 13589.3 13591.9 13618.4 8132.5 8453.2 8474.9 8580.4 8580.5 8476.3 8508.5 8546.0 8559.5 8622.5 6380.1 6560.4 6573.9 6588.1 6688.9 1829.0 1856.7 1856.8 1859.6 1864.5 740.5 838.0 924.5 1000.2 1021.1 21275.1 21349.0 21420.9 21484.5 21720.0 930.8 953.1 959.7 963.6 966.0 10165.7 11013.4 11990.3 12004.8 12017.4 629919.0 630158.0 646842.0 665520.0 667302.0 102082.0 127754.4 135839.0 136134.0 142081.0 945.5 988.3 994.8 1001.6 1003.3 18011.1 18890.9 19199.3 19255.9 19284.9 54223.9 55549.7 56136.2 57568.7 58434.0 75224.6 77198.0 78913.6 80157.6 83495.6 10207.4 10803.8 11660.2 12115.7 12404.9 12517.1 13317.7 13346.8 13552.5 13766.7 10683.6 10711.0 10737.8 10768.8 11115.0 7573.5 7697.3 11247.2 11314.2 11360.0 7915.8 7949.5 8016.6 8089.9 8114.0 11655.1 12244.4 12261.1 12312.8 12343.7 11176.7 11212.4 11228.8 11253.8 11453.3 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5309.23 Median:5192.83 stddev:2930.37 (56.4%) stddev-sans-first:2924.23 03:43:01 INFO - PROCESS | 1916 | Values: 1448.6 1487.9 1626.6 1712.2 1739.3 7511.5 7763.1 8013.9 8396.0 8397.8 7895.3 8138.1 8173.7 8225.5 8274.6 2288.4 2312.7 2315.8 2319.2 2327.4 2214.2 2242.2 2259.3 2266.5 2277.4 2234.4 2237.7 2239.7 2292.9 2320.5 1703.9 1880.4 1894.4 1903.9 2247.1 1850.9 2286.2 2330.6 2332.6 2332.9 7145.9 8222.2 8366.9 8372.9 8380.2 4447.3 5022.2 5191.8 5197.3 5199.2 3276.7 3296.5 3434.4 3460.7 3515.0 1021.1 1074.7 1079.0 1087.1 1093.2 10677.3 10719.4 10737.9 10819.6 10849.3 4486.0 4604.8 4684.6 4723.7 4732.0 9317.4 9358.2 9424.2 9435.8 9444.0 5094.0 5143.0 5191.6 5193.9 5199.6 3611.3 3710.6 4420.0 4661.4 4757.5 3773.2 3780.3 3835.6 3838.7 3899.7 1068.0 1262.4 1271.5 1274.7 1279.1 866.9 869.7 870.2 892.2 892.8 5601.5 5602.6 5779.2 5811.2 5903.8 815.7 892.0 907.3 908.5 959.6 3075.3 3702.4 3703.7 3768.1 3819.9 9478.0 10015.2 10044.9 10049.5 10063.5 9884.4 9886.2 9898.0 9986.9 10006.7 7608.7 7650.4 7700.7 7816.8 7859.6 5262.4 5302.7 5359.8 5408.0 5486.8 9270.8 9322.7 9418.5 9431.7 9436.5 7276.2 7666.5 8351.9 9560.6 9567.6 6002.8 6546.4 6572.6 6646.0 6717.4 7219.4 7824.3 7838.0 7867.0 7895.2 5448.9 5914.5 6705.8 7137.7 7208.9 6199.8 6809.8 6933.7 7005.3 7121.3 6597.8 6626.7 6627.3 6686.2 6695.4 4090.8 4621.4 4681.6 4828.0 4933.9 4748.1 4758.5 4794.4 4870.0 4890.9 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4497.74 Median:4982.55 stddev:1386.91 (27.8%) stddev-sans-first:1354.29 03:43:01 INFO - PROCESS | 1916 | Values: 285.9 309.7 310.1 310.2 318.5 4593.7 5306.3 5487.0 5498.4 5553.9 5361.2 5373.4 5380.6 5426.9 5437.2 3871.9 3953.9 4083.3 4120.9 4145.0 3051.6 3631.5 4025.4 4150.7 4163.0 3335.8 3646.8 4145.7 4166.6 4181.4 3787.0 3965.0 4108.3 4147.7 4180.4 3576.9 3594.6 3595.2 3613.7 3616.6 4048.9 4362.8 4453.9 4916.0 5276.9 4051.8 4262.7 4603.9 4730.2 4772.9 4916.8 5710.0 5736.6 5738.9 5755.3 3594.8 3628.7 3901.0 4001.0 4011.5 4735.8 5806.9 5973.6 5973.8 6049.8 3472.8 3511.2 3803.8 4038.5 4091.2 4078.8 4126.5 4127.9 4135.1 4136.9 3644.8 3786.3 4672.2 4715.1 4741.3 5006.4 5054.6 5553.2 5635.8 5777.9 1316.8 1337.5 1337.7 1338.6 1342.3 752.5 850.7 873.4 907.9 909.5 4812.8 5190.7 5266.2 5332.5 5456.0 4802.8 5495.9 5912.5 5944.7 5990.3 4759.8 5476.7 5487.8 5490.6 5504.4 2493.8 2543.0 2695.8 2884.3 2991.5 5823.0 5849.9 5851.5 5901.5 5921.0 5368.8 5444.9 5785.3 5794.8 5819.3 4955.9 5300.6 5821.4 5856.7 5895.7 4939.7 5355.0 5665.4 5687.0 5698.3 4510.6 4519.9 4961.8 5036.8 5620.8 4955.1 5087.9 5593.9 5709.7 5826.8 3504.5 3904.4 3905.3 3919.8 3928.4 5616.3 5624.7 5656.6 5663.4 5676.3 4862.7 5460.8 5535.9 5574.5 5602.5 5234.8 5267.7 5346.3 5432.0 5507.5 4294.3 4725.0 5003.3 5340.0 5347.2 4256.5 5140.4 5300.8 5478.0 5672.3 4329.5 5590.0 5591.0 5627.7 5678.5 03:43:01 INFO - PROCESS | 1916 | 03:43:01 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:19372.13 Median:25853.71 stddev:27744.97 (107.3%) stddev-sans-first:27787.32 03:43:01 INFO - PROCESS | 1916 | Values: 685.6 706.2 741.7 760.6 788.5 11443.5 13618.6 14378.9 14513.0 14567.6 10896.8 11019.3 11252.6 12499.6 12546.1 640.7 665.8 673.9 799.7 832.1 813.5 829.4 829.4 829.8 836.2 635.9 858.0 870.5 878.2 879.1 870.5 872.8 877.1 878.5 878.8 655.3 656.0 656.5 656.7 657.2 13298.2 13495.8 13592.9 13596.3 14180.5 10094.4 12801.7 12838.0 13137.5 13640.9 11676.2 12004.5 12047.6 12157.8 12334.1 4367.2 4462.3 4472.7 4483.9 4624.9 85367.6 85744.0 86984.0 87929.7 90634.5 9853.5 10013.9 10038.1 10041.6 10108.1 17856.8 17895.4 17916.3 17924.6 18322.5 7569.0 7569.8 7595.7 7662.0 7734.0 4104.4 4181.4 4248.0 4248.5 4301.4 3172.7 3221.0 3252.5 3278.1 3293.2 622.7 628.9 629.3 630.1 639.5 588.4 591.2 592.8 593.4 630.7 7952.0 8362.7 8629.3 8650.7 8888.4 497.4 523.2 543.1 563.9 602.7 4154.6 4309.3 4400.4 4531.5 4638.0 60539.0 69800.3 93040.5 105689.0 108012.0 88270.0 90530.0 92611.0 93739.1 94516.5 58999.7 72240.6 75789.2 76547.0 85858.6 9448.9 9478.9 9682.3 9755.9 9756.5 51210.0 51496.5 52075.5 56256.4 59756.7 62428.7 78648.8 79506.0 79541.6 81856.6 11806.6 12187.9 12199.8 12282.0 12397.3 14150.5 14241.2 14686.6 14870.6 14915.8 13065.9 13168.2 13313.8 13362.0 13854.5 10189.8 11494.7 12998.4 13058.9 13218.6 11470.2 12317.2 12581.9 12632.0 13026.0 10492.9 10592.6 10672.8 10701.8 10980.9 9517.4 11064.8 11166.2 11242.8 11265.6 03:43:01 INFO - PROCESS | 1916 | -------- Summary: end -------- 03:43:01 INFO - PROCESS | 1916 | 03:43:02 INFO - TEST-INFO | 1916: exit 0 03:43:02 INFO - TEST-OK | dromaeo_css | took 1116598ms 03:43:02 INFO - TEST-START | kraken 03:43:02 INFO - operating with platform_type : mac_ 03:43:02 INFO - Initialising browser for kraken test... 03:43:02 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpMQZyNo/profile http://localhost:50850/getInfo.html) 03:43:05 INFO - PROCESS | 1944 | 2016-11-29 03:43:05.190 plugin-container[1945:18445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 03:43:05 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:05 INFO - PROCESS | 1944 | 2016-11-29 03:43:05.194 plugin-container[1945:18445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:43:05 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:05 INFO - PROCESS | 1944 | 2016-11-29 03:43:05.194 plugin-container[1945:18445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:43:05 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 03:43:05 INFO - PROCESS | 1944 | colorDepth:24 03:43:05 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 03:43:05 INFO - PROCESS | 1944 | __metrics 03:43:05 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:43:06 INFO - TEST-INFO | 1944: exit 0 03:43:06 INFO - Browser initialized. 03:43:06 INFO - Running cycle 1/1 for kraken test... 03:43:06 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpMQZyNo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:09 INFO - PROCESS | 1946 | 2016-11-29 03:43:09.044 plugin-container[1947:18568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 03:43:09 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:09 INFO - PROCESS | 1946 | 2016-11-29 03:43:09.045 plugin-container[1947:18568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 03:43:09 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:09 INFO - PROCESS | 1946 | 2016-11-29 03:43:09.045 plugin-container[1947:18568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:43:18 INFO - PROCESS | 1946 | RSS: Main: 230125568 03:43:18 INFO - PROCESS | 1946 | 03:43:21 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50850/tests/kraken/driver.html?testName=audio-beat-detection) 03:43:22 INFO - PROCESS | 1946 | RSS: Main: 242610176 03:43:22 INFO - PROCESS | 1946 | 03:43:25 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50850/tests/kraken/driver.html?testName=audio-dft) 03:43:25 INFO - PROCESS | 1946 | RSS: Main: 250097664 03:43:25 INFO - PROCESS | 1946 | 03:43:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50850/tests/kraken/driver.html?testName=audio-fft) 03:43:29 INFO - PROCESS | 1946 | RSS: Main: 253767680 03:43:29 INFO - PROCESS | 1946 | 03:43:31 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50850/tests/kraken/driver.html?testName=audio-oscillator) 03:43:32 INFO - PROCESS | 1946 | RSS: Main: 255254528 03:43:32 INFO - PROCESS | 1946 | 03:43:34 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50850/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:43:34 INFO - PROCESS | 1946 | RSS: Main: 255410176 03:43:34 INFO - PROCESS | 1946 | 03:43:37 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50850/tests/kraken/driver.html?testName=imaging-darkroom) 03:43:38 INFO - PROCESS | 1946 | RSS: Main: 259473408 03:43:38 INFO - PROCESS | 1946 | 03:43:41 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50850/tests/kraken/driver.html?testName=imaging-desaturate) 03:43:42 INFO - PROCESS | 1946 | RSS: Main: 258404352 03:43:42 INFO - PROCESS | 1946 | 03:43:44 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50850/tests/kraken/driver.html?testName=json-parse-financial) 03:43:45 INFO - PROCESS | 1946 | RSS: Main: 259280896 03:43:45 INFO - PROCESS | 1946 | 03:43:47 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50850/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:43:47 INFO - PROCESS | 1946 | RSS: Main: 258154496 03:43:47 INFO - PROCESS | 1946 | 03:43:49 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:43:50 INFO - PROCESS | 1946 | RSS: Main: 258215936 03:43:50 INFO - PROCESS | 1946 | 03:43:52 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:43:53 INFO - PROCESS | 1946 | RSS: Main: 258232320 03:43:53 INFO - PROCESS | 1946 | 03:43:56 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:43:57 INFO - PROCESS | 1946 | RSS: Main: 258129920 03:43:57 INFO - PROCESS | 1946 | 03:44:00 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:00 INFO - PROCESS | 1946 | RSS: Main: 258535424 03:44:00 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50850/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50850/tests/kraken/driver.html?testName=ai-astar) 03:44:02 INFO - PROCESS | 1946 | RSS: Main: 259964928 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | __start_tp_report 03:44:02 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 03:44:02 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 03:44:02 INFO - PROCESS | 1946 | |i|pagename|runs| 03:44:02 INFO - PROCESS | 1946 | |0;ai-astar;170;106;168;178;150;119;134;121;126;172 03:44:02 INFO - PROCESS | 1946 | |1;audio-beat-detection;150;118;117;151;118;121;135;119;150;117 03:44:02 INFO - PROCESS | 1946 | |2;audio-dft;192;202;199;200;195;195;196;195;198;196 03:44:02 INFO - PROCESS | 1946 | |3;audio-fft;83;61;61;87;64;60;59;60;79;61 03:44:02 INFO - PROCESS | 1946 | |4;audio-oscillator;105;96;125;107;72;71;87;69;73;90 03:44:02 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;91;87;91;89;92;98;93;90;92;89 03:44:02 INFO - PROCESS | 1946 | |6;imaging-darkroom;97;96;97;96;98;96;144;155;140;95 03:44:02 INFO - PROCESS | 1946 | |7;imaging-desaturate;90;89;89;88;91;88;124;94;141;118 03:44:02 INFO - PROCESS | 1946 | |8;json-parse-financial;55;57;57;58;61;61;61;60;61;60 03:44:02 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;67;75;69;75;84;73;72;70;74;74 03:44:02 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;85;84;84;86;84;86;84;83;82;89 03:44:02 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;154;156;140;154;146;161;193;151;216;180 03:44:02 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;157;184;173;234;225;170;188;236;157;220 03:44:02 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;69;69;68;68;78;90;88;87;91;86 03:44:02 INFO - PROCESS | 1946 | __end_tp_report 03:44:02 INFO - PROCESS | 1946 | __start_cc_report 03:44:02 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,979 03:44:02 INFO - PROCESS | 1946 | __end_cc_report 03:44:02 INFO - PROCESS | 1946 | __startTimestamp1480419842890__endTimestamp 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | ------- Summary: start ------- 03:44:02 INFO - PROCESS | 1946 | Number of tests: 14 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:144.40 Median:159.00 stddev:26.35 (16.6%) stddev-sans-first:26.27 03:44:02 INFO - PROCESS | 1946 | Values: 170.0 106.0 168.0 178.0 150.0 119.0 134.0 121.0 126.0 172.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:128.00 stddev:15.25 (11.9%) stddev-sans-first:14.27 03:44:02 INFO - PROCESS | 1946 | Values: 150.0 118.0 117.0 151.0 118.0 121.0 135.0 119.0 150.0 117.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:196.80 Median:197.00 stddev:2.94 (1.5%) stddev-sans-first:2.55 03:44:02 INFO - PROCESS | 1946 | Values: 192.0 202.0 199.0 200.0 195.0 195.0 196.0 195.0 198.0 196.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.50 stddev:10.94 (17.5%) stddev-sans-first:10.06 03:44:02 INFO - PROCESS | 1946 | Values: 83.0 61.0 61.0 87.0 64.0 60.0 59.0 60.0 79.0 61.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:72.50 stddev:18.85 (26.0%) stddev-sans-first:19.14 03:44:02 INFO - PROCESS | 1946 | Values: 105.0 96.0 125.0 107.0 72.0 71.0 87.0 69.0 73.0 90.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:91.50 stddev:2.97 (3.3%) stddev-sans-first:3.15 03:44:02 INFO - PROCESS | 1946 | Values: 91.0 87.0 91.0 89.0 92.0 98.0 93.0 90.0 92.0 89.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:96.00 stddev:24.40 (25.4%) stddev-sans-first:25.31 03:44:02 INFO - PROCESS | 1946 | Values: 97.0 96.0 97.0 96.0 98.0 96.0 144.0 155.0 140.0 95.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:89.00 stddev:19.19 (21.6%) stddev-sans-first:19.92 03:44:02 INFO - PROCESS | 1946 | Values: 90.0 89.0 89.0 88.0 91.0 88.0 124.0 94.0 141.0 118.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.50 stddev:2.18 (3.6%) stddev-sans-first:1.74 03:44:02 INFO - PROCESS | 1946 | Values: 55.0 57.0 57.0 58.0 61.0 61.0 61.0 60.0 61.0 60.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:74.00 stddev:4.62 (6.2%) stddev-sans-first:4.30 03:44:02 INFO - PROCESS | 1946 | Values: 67.0 75.0 69.0 75.0 84.0 73.0 72.0 70.0 74.0 74.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.50 stddev:1.95 (2.3%) stddev-sans-first:2.06 03:44:02 INFO - PROCESS | 1946 | Values: 85.0 84.0 84.0 86.0 84.0 86.0 84.0 83.0 82.0 89.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:165.10 Median:158.50 stddev:23.88 (15.1%) stddev-sans-first:24.98 03:44:02 INFO - PROCESS | 1946 | Values: 154.0 156.0 140.0 154.0 146.0 161.0 193.0 151.0 216.0 180.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.40 Median:204.00 stddev:31.43 (15.4%) stddev-sans-first:30.28 03:44:02 INFO - PROCESS | 1946 | Values: 157.0 184.0 173.0 234.0 225.0 170.0 188.0 236.0 157.0 220.0 03:44:02 INFO - PROCESS | 1946 | 03:44:02 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:86.50 stddev:10.00 (11.6%) stddev-sans-first:9.88 03:44:02 INFO - PROCESS | 1946 | Values: 69.0 69.0 68.0 68.0 78.0 90.0 88.0 87.0 91.0 86.0 03:44:02 INFO - PROCESS | 1946 | -------- Summary: end -------- 03:44:02 INFO - PROCESS | 1946 | 03:44:03 INFO - TEST-INFO | 1946: exit 0 03:44:03 INFO - TEST-OK | kraken | took 61830ms 03:44:03 INFO - SUITE-END | took 1178s 03:44:04 INFO - Completed test suite (00:19:38) 03:44:04 INFO - javascript benchmark 03:44:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7182.737619152224, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2880.6975267596904, 3186.2193762528177, 3362.3107263011084, 3383.4924761285806, 3401.285729996699, 5703.828885133442, 5728.598998070137, 5753.683547404897, 5806.651600903938, 5825.475707186352, 5725.770969161229, 5844.298684157897, 5864.5894787364905, 5875.265591801025, 5888.941110588893, 5202.0376230397405, 5221.086309895772, 5224.346956630421, 5241.371035475749, 5317.574594032488, 5259.6055295852775, 5288.92066619001, 5301.496357846017, 5309.4425085365865, 5322.121849894763, 5197.324347834776, 5295.576353891699, 5306.257124002643, 5306.495882891119, 5353.384360798519, 5161.2516185152945, 5183.844484665447, 5227.947720522784, 5261.289725886994, 5292.941411717633, 5161.870953226162, 5167.173252279655, 5198.298229738974, 5232.790688372461, 5276.551493123089, 5572.136318870582, 5768.077107662796, 5804.0, 5819.912701309441, 5850.76596936122, 6150.50795936324, 6178.783742569033, 6183.474404675608, 6214.347493513161, 6263.154474146023, 6264.9686751566405, 6311.400416960381, 6313.0, 6323.177986861724, 6347.174867267226, 5742.799002034993, 5903.2325797646035, 5925.4370834770625, 5938.940610593925, 5947.405259474018, 5200.7139607321205, 6249.312575616678, 6274.309825919146, 6286.308506064329, 6303.2751233607805, 6164.075388691732, 6179.042248451407, 6188.690565471708, 6191.504679625619, 6208.068789681584, 6113.141862489121, 6170.228721409824, 6194.5973511721595, 6263.96868015657, 6284.685765711696, 6207.6275423474735, 6213.0, 6257.499400047986, 6278.246610406781, 6295.087212354275, 6271.247450305992, 6286.214223222098, 6286.874262514815, 6294.402031806971, 6326.082718005863, 4797.0, 4905.484924082992, 4935.629827762932, 4970.751462426936, 5006.574441172541, 4397.516273210008, 4428.180786554498, 4450.06548624786, 4470.0, 4555.2483840166005, 4538.04701012791, 5883.499902508249, 5915.852103697442, 5989.401059893976, 5990.64056156632, 6172.321044685081, 6295.905561416583, 6303.558750887394, 6305.684715764193, 6327.0, 5719.399463056402, 5762.366139724627, 5814.970925145347, 5882.705864706748, 5902.734376953047, 5342.011727830286, 5357.973210133924, 5380.139177731544, 5400.351957765093, 5410.107332290207, 6288.874222515524, 6303.0, 6320.0, 6335.36646335372, 6345.841353966188, 6120.724567394389, 6249.562530622813, 6253.96873015632, 6287.1512345832725, 6295.276043255084, 6233.875322493434, 6288.339724328879, 6296.275928268308, 6307.148534947723, 6325.209348831396, 6256.718447669775, 6258.342873998165, 6280.309165991828, 6283.497320214463, 6287.30839607652, 6123.989541725566, 6164.229471316085, 6250.187475628321, 6319.747210111728, 6345.1434056401795, 6092.664903430354, 6110.042400473335, 6133.815985520441, 6150.815475535741, 6165.414285642857, 6179.0, 6191.9690401547705, 6194.876102478015, 6200.193974783249, 6215.720292586935, 5905.822825315247, 6058.363871793398, 6095.9390406097145, 6110.480609148272, 6138.815835524942, 5997.640141591519, 6117.480014198843, 6134.263888333428, 6165.229346331708, 6204.3795620437595, 5919.0, 5998.100284957117, 6026.337102918763, 6072.544559158169, 6138.539609529393], "value": 5734.209170698346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.33018260257353, 507.9517445842648, 510.3263691926656, 517.6376536424501, 526.7866514061807, 35957.0, 39710.82313414931, 39722.40416393753, 41699.0, 41737.373939390935, 32392.19019524507, 40778.7961060195, 41186.58813411865, 41207.17585648285, 41408.0, 7414.406847452191, 7887.369010479148, 9303.767405814875, 9469.81060378792, 9473.19477844384, 9338.439693618371, 9373.718788436323, 9468.952655236713, 9485.762855928588, 9492.905070949306, 9479.289053320994, 9480.431174129575, 9519.095685909828, 9523.904760952371, 9535.618575256982, 7936.4047696423, 9418.81162376752, 9435.962044175136, 9478.999999999965, 9500.809983800285, 9265.0, 9338.019507951634, 9340.906590934072, 9345.813083738356, 9351.064893510662, 38939.80530097331, 38950.220995579926, 39232.80383598092, 39295.41056884149, 39341.803290983364, 3415.2486452980306, 3448.189675426298, 3531.964680353189, 3562.3943929532043, 3683.9869036015043, 29194.562081568794, 29371.0, 29663.110106696833, 30216.8489157555, 30236.395272094655, 3076.2928622812005, 3160.5891234139413, 3164.620245570546, 3177.8887738929025, 3188.984055079733, 459030.0, 466170.0, 468609.99999999657, 469570.0, 470183.0, 61000.0, 61568.0, 61616.075758863655, 61677.0, 61761.76476470535, 62954.37045629485, 64255.71488570296, 64351.0, 64602.353976460574, 64660.383490413114, 15892.284847181905, 16168.0, 16257.837421625869, 16391.180440977903, 16392.098434585972, 12454.816792404707, 12491.750164996649, 12509.124361294804, 12563.18339307942, 12607.621771346874, 17424.825751742574, 18492.445226643224, 18536.351227706964, 18593.34923277679, 18647.44057678272, 7483.214262502468, 7605.961970190114, 7675.7718764997335, 7705.075390953009, 7735.381169506426, 775.3680750188691, 897.3987428422972, 927.5501381829807, 946.5030858799048, 982.0964712464549, 29590.112296631134, 29975.602983179735, 30455.390892182033, 31235.375292494024, 31663.683363166074, 929.4330458420358, 996.8504724291414, 1049.480507148951, 1075.2043487818958, 1126.549380247891, 13644.113132646546, 15209.847901521065, 15222.086674799548, 15265.465708700141, 15388.61528461775, 608784.0, 673014.0, 682673.0, 683466.0, 683664.0, 54666.0, 55050.0, 55052.0, 55077.44922550723, 55189.72405138029, 1338.8795008449285, 1342.3690865293297, 1372.4647387518676, 1379.8689124533153, 1386.4454218312756, 26532.806023729365, 28156.71843281541, 28744.425111498073, 28968.84124634991, 29557.408851823275, 45416.0, 49531.0, 49559.00881982412, 49603.50396495989, 49692.0, 597106.0, 597543.0, 604112.0, 604443.0, 605089.0, 25550.488990220463, 25998.87000564985, 26894.731052690004, 26938.0, 27757.58363624507, 45632.0, 45687.0, 45966.540334596226, 46169.76915115403, 46202.0, 37176.07059823526, 37319.81340093282, 37926.0, 39048.6095139045, 39346.0, 29513.0, 32936.0, 37534.8123259382, 37684.0, 38780.41829372618, 28789.0, 35504.82247588746, 36709.0, 36885.81557092197, 37277.25445491021, 32361.35277294488, 33617.66382336145, 34427.82786086053, 34428.31143377168, 34472.482912755826, 22713.88643056774, 25263.747362526876, 25471.745282546937, 25884.0, 25984.51565178734], "value": 19578.220850737787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.77775446064993, 366.8789299531155, 368.9520362352895, 378.134072972892, 397.3245482679444, 18823.247070117195, 19528.414147575597, 19613.41159765206, 20198.69701954468, 20300.0, 19607.607847843035, 19772.90113549434, 20198.980101989793, 20376.898115509437, 20571.0, 5294.1529355303155, 5534.612577119603, 5784.82645520635, 6064.666443345614, 6077.604955677867, 5848.210491583645, 6049.758009679629, 6050.576459647827, 6052.273727152748, 6065.211522502069, 5668.348139963916, 5880.911786323209, 5935.49548288396, 5990.760369585211, 6022.759089636431, 5840.06558950568, 5891.351951285355, 5913.349531551526, 5920.993431116721, 5926.4073592640825, 5037.4458809530925, 5061.645684802065, 5199.220116982445, 5202.0, 5222.16445368743, 24450.877745611335, 24705.382365441008, 24828.751712482826, 24851.378715532257, 25070.874645626838, 2426.9393265168337, 2462.175171317611, 2907.447584958872, 2975.8958436454836, 3062.173213232437, 14856.480023199245, 14907.329170187368, 15049.924750376178, 15718.607034824106, 15886.841131588651, 1829.1128802530761, 1860.692985657365, 1918.9712154317697, 1922.7404300419405, 1952.9023548822645, 112928.0, 115421.42289288584, 115438.0, 115760.0, 116023.0, 10488.05607495329, 10649.361038337725, 10892.128629709605, 11007.284526505751, 11095.44522773858, 13558.322083895962, 13559.050866439255, 13589.252591107585, 13591.86408135906, 13618.387172577257, 8132.471389359673, 8453.239208471266, 8474.915250847536, 8580.399372043898, 8580.485170889766, 8476.321894248445, 8508.489490630582, 8545.957270213734, 8559.529225892511, 8622.482651040958, 6380.097055839582, 6560.409563139245, 6573.934260657428, 6588.143541339595, 6688.933110668831, 1828.9817101828812, 1856.7214917762444, 1856.7957524672274, 1859.6280743851285, 1864.4686264414577, 740.4668638580214, 837.9790505237418, 924.4638109896244, 1000.1998401278947, 1021.1473419694489, 21275.085242328263, 21349.0, 21420.892895535424, 21484.462888427915, 21720.022598983083, 930.8277968575835, 953.1326492891435, 959.6545243712258, 963.643451922793, 965.9806803863884, 10165.69502914899, 11013.403056556754, 11990.280583165038, 12004.819927701092, 12017.377654016596, 629919.0, 630158.0, 646842.0, 665520.0, 667302.0, 102082.0, 127754.36122819512, 135839.0, 136134.0, 142081.0, 945.5414124149781, 988.3279370028306, 994.8308787506141, 1001.5993602558889, 1003.307717674802, 18011.099445027958, 18890.905545472186, 19199.32802351911, 19255.903720481307, 19284.903575482032, 54223.915521688556, 55549.722251388484, 56136.24130300426, 57568.71215643895, 58434.0, 75224.62387688027, 77198.0, 78913.60543197476, 80157.59921200357, 83495.58252208943, 10207.438590877573, 10803.75756787948, 11660.242084264495, 12115.697107572381, 12404.875951240372, 12517.061220408688, 13317.66705832362, 13346.799798002843, 13552.457901683822, 13766.65583360424, 10683.626073087406, 10711.0, 10737.785244294913, 10768.784624307313, 11115.0, 7573.46985710995, 7697.307242348215, 11247.156463265124, 11314.15143864197, 11359.977602015859, 7915.802104947192, 7949.483283586548, 8016.599170041592, 8089.9191008089165, 8113.959430202811, 11655.067594592243, 12244.367460995929, 12261.080418968791, 12312.753744924872, 12343.69140771452, 11176.664700059011, 11212.43937803123, 11228.775424491301, 11253.7749245013, 11453.31280123162], "value": 9900.018654994172, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.5726710620365, 1487.9107253564787, 1626.6258760485089, 1712.195268223936, 1739.260814153985, 7511.511751736133, 7763.107242667095, 8013.879791803128, 8395.958020209906, 8397.790055248613, 7895.289423951843, 8138.0641226258995, 8173.67305307787, 8225.547594882279, 8274.62764175612, 2288.3706980580305, 2312.6762253284555, 2315.780000899912, 2319.2346525646494, 2327.3948773318975, 2214.191819985702, 2242.237639202671, 2259.3222033389998, 2266.456050547873, 2277.430642339415, 2234.441389652587, 2237.7314722233355, 2239.6864438978555, 2292.919747808827, 2320.5010922651627, 1703.855172310349, 1880.3888736160734, 1894.4032629721594, 1903.8671990165828, 2247.0899285789283, 1850.917213430145, 2286.2341115726254, 2330.6154484510034, 2332.5684748321446, 2332.918347857834, 7145.92854071463, 8222.218889205516, 8366.916330836675, 8372.916270837275, 8380.20388063133, 4447.332900064984, 5022.196448568248, 5191.792328306863, 5197.324347834795, 5199.168133098724, 3276.7050965413227, 3296.4890441981292, 3434.364642541113, 3460.723142148622, 3514.9824250878837, 1021.1167340250704, 1074.7152004718755, 1079.02348374721, 1087.141158484796, 1093.191038631415, 10677.25259231854, 10719.356838589709, 10737.926207379278, 10819.621313253998, 10849.294795838245, 4485.977570112129, 4604.815807367668, 4684.5549672781035, 4723.669343146015, 4731.95268047322, 9317.44095354281, 9358.204552612968, 9424.246060315296, 9435.811283774286, 9444.0, 5094.032133894548, 5142.97428512855, 5191.5846732261325, 5193.870153246124, 5199.584033277329, 3611.3138503684218, 3710.6474884885893, 4420.005498762817, 4661.370714953506, 4757.500462451393, 3773.1699026214187, 3780.3006443807985, 3835.5972622874756, 3838.673712734449, 3899.7075219358094, 1067.962621308266, 1262.4129779652505, 1271.4659842865833, 1274.7004453953314, 1279.1046267612708, 866.9089745576749, 869.7216890595075, 870.1776820904184, 892.1791951404723, 892.821435712847, 5601.467860553241, 5602.551795856403, 5779.190913272145, 5811.215485909433, 5903.822885313448, 815.6981916690744, 891.9519564511672, 907.3285768531372, 908.5139450394007, 959.5538074795254, 3075.308055687497, 3702.3520883845217, 3703.7222208334483, 3768.095657042345, 3819.9427008594894, 9478.0, 10015.248856335804, 10044.949775251076, 10049.497525123861, 10063.5471403787, 9884.406935583887, 9886.159676427584, 9898.010198980044, 9986.900130998742, 10006.69979900604, 7608.657610407545, 7650.42621803367, 7700.730474433368, 7816.765497035098, 7859.646315915683, 5262.421133675216, 5302.707786743139, 5359.758810853443, 5408.0, 5486.807961721321, 9270.814583708423, 9322.720318390187, 9418.529073546433, 9431.669891553762, 9436.52817359143, 7276.163241227327, 7666.501677390952, 8351.874721879298, 9560.56977436031, 9567.61729530811, 6002.789902353396, 6546.443552298108, 6572.638504882086, 6646.0, 6717.395434410926, 7219.422446204186, 7824.334931530883, 7837.960810195913, 7867.0, 7895.171007044178, 5448.918266226089, 5914.467697907209, 6705.832354191184, 7137.714491420493, 7208.89186662211, 6199.814005579839, 6809.829754255985, 6933.722651094102, 7005.299470052954, 7121.287871212838, 6597.835054123685, 6626.701798418986, 6627.271000190071, 6686.197656281277, 6695.4308883745425, 4090.836366545305, 4621.445426548836, 4681.578657920803, 4827.975860120818, 4933.9013219736125, 4748.05038992196, 4758.547937945889, 4794.424669039738, 4870.0, 4890.853274401774], "value": 4304.301706165732, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.86369182499567, 309.6995913963455, 310.09916193458014, 310.15328154139183, 318.5444813916101, 4593.678442509022, 5306.257124002643, 5487.0, 5498.367687715914, 5553.8898216998505, 5361.169018802092, 5373.361124856917, 5380.623356365056, 5426.864328391792, 5437.184422336642, 3871.9225615487812, 3953.9011524711896, 4083.3462447708725, 4120.876373708778, 4144.979275103636, 3051.5575241589954, 3631.455281707752, 4025.409963064588, 4150.730202536825, 4163.0, 3335.783174093688, 3646.762960407565, 4145.730527515701, 4166.625003749662, 4181.393697913801, 3786.9775160706595, 3965.0174664916003, 4108.26051310764, 4147.688923330732, 4180.393842892779, 3576.9269219234384, 3594.6045934947133, 3595.2090540081404, 3613.7470377073614, 3616.620254873253, 4048.9430479987714, 4362.803673834654, 4453.933191002106, 4915.967646794142, 5276.894462110775, 4051.8379264829373, 4262.65898728101, 4603.861884143447, 4730.195866702668, 4772.856814295542, 4916.778744956486, 5709.97446800005, 5736.569757268222, 5738.885222295572, 5755.30936287644, 3594.820258987066, 3628.6915612172997, 3900.980495097535, 4000.959510649561, 4011.452727518018, 4735.763211839394, 5806.854828629276, 5973.611715238539, 5973.761049558012, 6049.818505444844, 3472.7569070164845, 3511.2099777549847, 3803.7717736935874, 4038.4749982502676, 4091.2226676931286, 4078.817143028548, 4126.525449573277, 4127.938080928789, 4135.069609337875, 4136.896577585524, 3644.835982380799, 3786.2772438540946, 4672.2057250267535, 4715.147335861754, 4741.336212930192, 5006.3992320921725, 5054.635248482962, 5553.195636203772, 5635.802746903839, 5777.913331300034, 1316.8288122544009, 1337.458329376604, 1337.6789570503008, 1338.598420473854, 1342.3489607540334, 752.5183882315323, 850.7362717557577, 873.4322690251388, 907.9364444488955, 909.5225006871445, 4812.807487700523, 5190.6885586864155, 5266.235226506038, 5332.454247582471, 5455.972720136453, 4802.807887684523, 5495.900819835969, 5912.497437717756, 5944.740998420572, 5990.281166260077, 4759.809607615726, 5476.659237864899, 5487.807926722546, 5490.588205884575, 5504.394516603171, 2493.7905116018715, 2542.987285063563, 2695.7759848125374, 2884.298264024194, 2991.491446454108, 5822.9518686635965, 5849.88300233993, 5851.473367396955, 5901.498372638288, 5921.0, 5368.8389348319615, 5444.91832622511, 5785.342799046808, 5794.82615521535, 5819.301683797971, 4955.934474088089, 5300.561741598831, 5821.359650438449, 5856.736446859816, 5895.734691938873, 4939.653664509329, 5355.0, 5665.405132461032, 5686.9715651421475, 5698.2592263005545, 4510.564295280512, 4519.932201017054, 4961.7519124044375, 5036.765992331902, 5620.831375058591, 4955.069705642908, 5087.870959359893, 5593.860153496195, 5709.743061562157, 5826.785821388015, 3504.456809194579, 3904.39481880309, 3905.2941205847455, 3919.7844118573753, 3928.4107383892647, 5616.269884914935, 5624.74688639004, 5656.5959467227085, 5663.377028526941, 5676.33212390176, 4862.70823750562, 5460.771326451517, 5535.944640553543, 5574.526165275997, 5602.52378547828, 5234.80138341905, 5267.7366131694025, 5346.25152478661, 5432.0, 5507.531859791802, 4294.334378171388, 4724.952750472451, 5003.3495645565845, 5339.997500937148, 5347.224652425454, 4256.489221293465, 5140.43455219933, 5300.80731835334, 5478.01395748769, 5672.347680016685, 4329.502107257706, 5589.965856316594, 5591.021571225101, 5627.718614069362, 5678.5457163426], "value": 4057.968653572075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.6160550091948, 706.2443185791204, 741.6662501874158, 760.6196901549225, 788.4874831359617, 11443.485043173056, 13618.591442256737, 14378.92158088145, 14512.984091113627, 14567.562973110811, 10896.782064358707, 11019.338839669625, 11252.606158784443, 12499.562515311965, 12546.059045571596, 640.6540468147207, 665.7836203233944, 673.865226954611, 799.7041094794934, 832.142892820393, 813.515958004986, 829.3986859526855, 829.4484168028254, 829.8257365953157, 836.1638361638362, 635.8855406026913, 857.995710021449, 870.4820631724145, 878.2227728460294, 879.0725784297564, 870.5299138465222, 872.8123453457505, 877.0659247900965, 878.5299864572455, 878.8330217258732, 655.3119224813973, 655.9573627714183, 656.4977791989132, 656.7471523463453, 657.1982181738272, 13298.202107873558, 13495.797563036562, 13592.86407135926, 13596.252206128658, 14180.50368237117, 10094.394336339843, 12801.679958001032, 12838.0, 13137.474501019948, 13640.863591364157, 11676.1826672133, 12004.459799309052, 12047.638570842888, 12157.817632735516, 12334.136610437274, 4367.213901497729, 4462.263726485126, 4472.709273897186, 4483.932741008887, 4624.861254162381, 85367.57316213442, 85744.0, 86984.0, 87929.68105478423, 90634.5468272661, 9853.50732463374, 10013.949930250303, 10038.146757525692, 10041.648542300984, 10108.090271875566, 17856.821431785775, 17895.37366192177, 17916.283348665907, 17924.641507169785, 18322.541936451697, 7568.962155189189, 7569.810754731176, 7595.658195381111, 7661.961690191625, 7734.0, 4104.384342348671, 4181.393697913785, 4248.022954720432, 4248.532661407243, 4301.397804307399, 3172.7303179230025, 3221.0336898930227, 3252.5121231815415, 3278.131295206772, 3293.193167673935, 622.6544267931341, 628.9371062893674, 629.2857606616524, 630.1052505442192, 639.5107742576839, 588.4439204951366, 591.2373038780012, 592.846912754688, 593.3739904400899, 630.6594439002887, 7952.0, 8362.665493380318, 8629.266512346396, 8650.6972255972, 8888.422252553562, 497.4478329054746, 523.2047288122034, 543.0794802809279, 563.8703098287417, 602.6685323072293, 4154.563770804082, 4309.28896732042, 4400.405945197422, 4531.524189960007, 4637.97964447821, 60539.0, 69800.3019969804, 93040.53479732694, 105689.0, 108012.0, 88270.0, 90530.0, 92611.0, 93739.06260937304, 94516.52741736385, 58999.705001474715, 72240.63879680568, 75789.24210757822, 76547.0, 85858.57070714732, 9448.858267126136, 9478.894145564143, 9682.273829462678, 9755.902440975784, 9756.51217439111, 51209.97580048304, 51496.48503514917, 52075.47924520706, 56256.43743562676, 59756.72554381901, 62428.68785656225, 78648.82026769489, 79506.0, 79541.60229198817, 81856.59071704603, 11806.645800625995, 12187.939060304641, 12199.817002745143, 12282.0, 12397.318147501974, 14150.504732334319, 14241.21673307978, 14686.55940321792, 14870.553883383516, 14915.776263355841, 13065.869341306465, 13168.209907405586, 13313.800292995418, 13362.0, 13854.515091971733, 10189.796204076025, 11494.712632184262, 12998.415071322002, 13058.869411305765, 13218.60344189676, 11470.19708620422, 12317.199382040139, 12581.87418125807, 12632.0, 13026.0, 10492.895071049192, 10592.576296948344, 10672.83990740124, 10701.839472408075, 10980.890191098306, 9517.381370210915, 11064.834027489433, 11166.218364714392, 11242.83135752948, 11265.605703800327], "value": 7072.9003152396, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 106.0, 168.0, 178.0, 150.0, 119.0, 134.0, 121.0, 126.0, 172.0], "value": 144.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 118.0, 117.0, 151.0, 118.0, 121.0, 135.0, 119.0, 150.0, 117.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 202.0, 199.0, 200.0, 195.0, 195.0, 196.0, 195.0, 198.0, 196.0], "value": 196.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 61.0, 87.0, 64.0, 60.0, 59.0, 60.0, 79.0, 61.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 96.0, 125.0, 107.0, 72.0, 71.0, 87.0, 69.0, 73.0, 90.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 89.0, 92.0, 98.0, 93.0, 90.0, 92.0, 89.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 98.0, 96.0, 144.0, 155.0, 140.0, 95.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 88.0, 91.0, 88.0, 124.0, 94.0, 141.0, 118.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 58.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 69.0, 75.0, 84.0, 73.0, 72.0, 70.0, 74.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 84.0, 86.0, 84.0, 83.0, 82.0, 89.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 140.0, 154.0, 146.0, 161.0, 193.0, 151.0, 216.0, 180.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 184.0, 173.0, 234.0, 225.0, 170.0, 188.0, 236.0, 157.0, 220.0], "value": 194.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 78.0, 90.0, 88.0, 87.0, 91.0, 86.0], "value": 79.4, "unit": "score"}]}]} 03:44:04 INFO - Return code: 0 03:44:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:44:04 ERROR - # TBPL SUCCESS # 03:44:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:44:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:44:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:44:04 INFO - 2016-11-29 03:44:04.201 screenresolution[1951:18943] starting screenresolution argv=screenresolution get 03:44:04 INFO - 2016-11-29 03:44:04.204 screenresolution[1951:18943] Display 0: 1600x1200x32@60 03:44:04 INFO - 2016-11-29 03:44:04.210 screenresolution[1952:18946] starting screenresolution argv=screenresolution list 03:44:04 INFO - Available Modes on Display 0 03:44:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:44:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:44:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:44:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:44:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:44:04 INFO - Intel Iris: 03:44:04 INFO - Chipset Model: Intel Iris 03:44:04 INFO - Type: GPU 03:44:04 INFO - Bus: Built-In 03:44:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:44:04 INFO - Vendor: Intel (0x8086) 03:44:04 INFO - Device ID: 0x0a2e 03:44:04 INFO - Revision ID: 0x0009 03:44:04 INFO - Displays: 03:44:04 INFO - Display: 03:44:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:44:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:44:04 INFO - Display Serial Number: 1600x1200 60 03:44:04 INFO - Main Display: Yes 03:44:04 INFO - Mirror: Off 03:44:04 INFO - Online: Yes 03:44:04 INFO - Rotation: Supported 03:44:04 INFO - Return code: 0 03:44:04 INFO - Running post-action listener: _resource_record_post_action 03:44:04 INFO - [mozharness: 2016-11-29 11:44:04.460422Z] Finished run-tests step (success) 03:44:04 INFO - Running post-run listener: _resource_record_post_run 03:44:04 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1766912; Write bytes: 660502528; Read time: 17; Write time: 1951 03:44:04 INFO - TinderboxPrint: CPU usage
54.6% 03:44:04 INFO - TinderboxPrint: I/O read bytes / time
1,766,912 / 17 03:44:04 INFO - TinderboxPrint: I/O write bytes / time
660,502,528 / 1,951 03:44:04 INFO - TinderboxPrint: CPU idle
2,200.0 (45.4%) 03:44:04 INFO - TinderboxPrint: CPU system
984.3 (20.3%) 03:44:04 INFO - TinderboxPrint: CPU user
1,657.5 (34.2%) 03:44:04 INFO - TinderboxPrint: Swap in / out
350,363,648 / 0 03:44:04 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345387520; Write bytes: 344688640; Read time: 26755; Write time: 744 03:44:04 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1725952; Write bytes: 308625408; Read time: 15; Write time: 1176 03:44:04 INFO - Running post-run listener: _upload_blobber_files 03:44:04 INFO - Blob upload gear active. 03:44:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:44:04 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 03:44:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:44:05 INFO - (blobuploader) - INFO - Open directory for files ... 03:44:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:44:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:44:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:06 INFO - (blobuploader) - INFO - Done attempting. 03:44:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:44:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:44:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:07 INFO - (blobuploader) - INFO - Done attempting. 03:44:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:44:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:44:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:09 INFO - (blobuploader) - INFO - Done attempting. 03:44:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:44:09 INFO - Return code: 0 03:44:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:44:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:44:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ef7292834c5c3698f92f8d347f0dbff94d1de56289958e8c26d8d48860ed7836f939111ebf43f9f7d46b970fd02fd13a1dc86068b672ec7ef27ae95dee02e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7a12ebbb12fb9a0c719319c08f604358537f448d664a48c37063633742bc04854a3bdf05dbede8a85f29904094d67898bc18f9476b4455f9eecb32855c78087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e329391fb297def51ede6b2024452214b79b76a41e01b4489dc7c3e9c07c833ed7e072738c0bb37a748601712a9db9493b0ca6c642b64e24cf1f7f1233b5f6c2"} 03:44:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:44:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:44:09 INFO - Contents: 03:44:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ef7292834c5c3698f92f8d347f0dbff94d1de56289958e8c26d8d48860ed7836f939111ebf43f9f7d46b970fd02fd13a1dc86068b672ec7ef27ae95dee02e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7a12ebbb12fb9a0c719319c08f604358537f448d664a48c37063633742bc04854a3bdf05dbede8a85f29904094d67898bc18f9476b4455f9eecb32855c78087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e329391fb297def51ede6b2024452214b79b76a41e01b4489dc7c3e9c07c833ed7e072738c0bb37a748601712a9db9493b0ca6c642b64e24cf1f7f1233b5f6c2"} 03:44:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:44:09 INFO - Copying logs to upload dir... 03:44:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:44:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.248198 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-29 03:44:09.612267) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 03:44:09.616047) ========= 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.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ef7292834c5c3698f92f8d347f0dbff94d1de56289958e8c26d8d48860ed7836f939111ebf43f9f7d46b970fd02fd13a1dc86068b672ec7ef27ae95dee02e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7a12ebbb12fb9a0c719319c08f604358537f448d664a48c37063633742bc04854a3bdf05dbede8a85f29904094d67898bc18f9476b4455f9eecb32855c78087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e329391fb297def51ede6b2024452214b79b76a41e01b4489dc7c3e9c07c833ed7e072738c0bb37a748601712a9db9493b0ca6c642b64e24cf1f7f1233b5f6c2"} build_url:https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/Cg2ajov9T2SkT2DIM9q79w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58ef7292834c5c3698f92f8d347f0dbff94d1de56289958e8c26d8d48860ed7836f939111ebf43f9f7d46b970fd02fd13a1dc86068b672ec7ef27ae95dee02e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7a12ebbb12fb9a0c719319c08f604358537f448d664a48c37063633742bc04854a3bdf05dbede8a85f29904094d67898bc18f9476b4455f9eecb32855c78087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e329391fb297def51ede6b2024452214b79b76a41e01b4489dc7c3e9c07c833ed7e072738c0bb37a748601712a9db9493b0ca6c642b64e24cf1f7f1233b5f6c2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 03:44:09.650801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:44:09.651137) ========= 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.Tki30VSpXw/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.83iJ9ahFD7/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.005119 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 03:44:09.700535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 03:44:09.700910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 03:44:09.701243) ========= ========= Total master_lag: 0.24 =========