builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0374 starttime: 1480437665.64 results: success (0) buildid: 20161129062926 builduid: 048a0259962446d380020565c40f4e96 revision: 130bb29013a1d089dd533e50e170eabedce157d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.637244) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.637682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.637983) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.666899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.667183) ========= 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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:05-- 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% 155M=0s 2016-11-29 08:41:05 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104947 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.798871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.799190) ========= 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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.025505 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.847215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:05.847531) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 130bb29013a1d089dd533e50e170eabedce157d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 130bb29013a1d089dd533e50e170eabedce157d8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:05,949 truncating revision to first 12 chars 2016-11-29 08:41:05,949 Setting DEBUG logging. 2016-11-29 08:41:05,949 attempt 1/10 2016-11-29 08:41:05,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/130bb29013a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 08:41:06,434 unpacking tar archive at: mozilla-central-130bb29013a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.762515 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:06.636396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:06.639759) ========= script_repo_revision: 130bb29013a1d089dd533e50e170eabedce157d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:06.640198) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:06.640502) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 08:41:06.659911) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-29 08:41:06.660219) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:06 INFO - MultiFileLogger online at 20161129 08:41:06 in /builds/slave/test 08:41:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:41:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:41:06 INFO - {'append_to_log': False, 08:41:06 INFO - 'base_work_dir': '/builds/slave/test', 08:41:06 INFO - 'blob_upload_branch': 'Firefox', 08:41:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:41:06 INFO - 'branch': 'Firefox', 08:41:06 INFO - 'buildbot_json_path': 'buildprops.json', 08:41:06 INFO - 'config_files': ('talos/mac_config.py',), 08:41:06 INFO - 'default_actions': ('clobber', 08:41:06 INFO - 'read-buildbot-config', 08:41:06 INFO - 'download-and-extract', 08:41:06 INFO - 'populate-webroot', 08:41:06 INFO - 'create-virtualenv', 08:41:06 INFO - 'install', 08:41:06 INFO - 'run-tests'), 08:41:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:41:06 INFO - 'download_minidump_stackwalk': True, 08:41:06 INFO - 'download_symbols': 'ondemand', 08:41:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:41:06 INFO - 'tooltool.py': '/tools/tooltool.py', 08:41:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:41:06 INFO - '/tools/misc-python/virtualenv.py')}, 08:41:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:41:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:41:06 INFO - 'installer_path': 'installer.exe', 08:41:06 INFO - 'log_level': 'info', 08:41:06 INFO - 'log_name': 'talos', 08:41:06 INFO - 'log_to_console': True, 08:41:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:41:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:41:06 INFO - 'opt_config_files': (), 08:41:06 INFO - 'pip_index': False, 08:41:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:06 INFO - 'cmd': ('bash', 08:41:06 INFO - '-c', 08:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:06 INFO - 'enabled': True, 08:41:06 INFO - 'halt_on_failure': False, 08:41:06 INFO - 'name': 'check_screen_resolution'},), 08:41:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:06 INFO - 'cmd': ('bash', 08:41:06 INFO - '-c', 08:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:06 INFO - 'enabled': True, 08:41:06 INFO - 'halt_on_failure': False, 08:41:06 INFO - 'name': 'check_screen_resolution'},), 08:41:06 INFO - 'run_cmd_checks_enabled': True, 08:41:06 INFO - 'sps_profile': False, 08:41:06 INFO - 'sps_profile_interval': 0, 08:41:06 INFO - 'suite': 'dromaeojs-e10s', 08:41:06 INFO - 'system_bits': '32', 08:41:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:41:06 INFO - 'title': 't-yosemite-r7-0374', 08:41:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:41:06 INFO - 'use_talos_json': True, 08:41:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:41:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:41:06 INFO - 'work_dir': 'build'} 08:41:06 INFO - [mozharness: 2016-11-29 16:41:06.866566Z] Running clobber step. 08:41:06 INFO - Running pre-action listener: _resource_record_pre_action 08:41:06 INFO - Running main action method: clobber 08:41:06 INFO - rmtree: /builds/slave/test/build 08:41:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:41:09 INFO - Running post-action listener: _resource_record_post_action 08:41:09 INFO - [mozharness: 2016-11-29 16:41:09.227051Z] Finished clobber step (success) 08:41:09 INFO - [mozharness: 2016-11-29 16:41:09.227300Z] Running read-buildbot-config step. 08:41:09 INFO - Running pre-action listener: _resource_record_pre_action 08:41:09 INFO - Running main action method: read_buildbot_config 08:41:09 INFO - Using buildbot properties: 08:41:09 INFO - { 08:41:09 INFO - "project": "", 08:41:09 INFO - "product": "firefox", 08:41:09 INFO - "script_repo_revision": "production", 08:41:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:41:09 INFO - "repository": "", 08:41:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:41:09 INFO - "buildid": "20161129062926", 08:41:09 INFO - "pgo_build": "False", 08:41:09 INFO - "basedir": "/builds/slave/test", 08:41:09 INFO - "buildnumber": 179, 08:41:09 INFO - "slavename": "t-yosemite-r7-0374", 08:41:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:41:09 INFO - "platform": "yosemite_r7", 08:41:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:41:09 INFO - "branch": "mozilla-central", 08:41:09 INFO - "repo_path": "mozilla-central", 08:41:09 INFO - "revision": "130bb29013a1d089dd533e50e170eabedce157d8", 08:41:09 INFO - "stage_platform": "macosx64", 08:41:09 INFO - "builduid": "048a0259962446d380020565c40f4e96", 08:41:09 INFO - "slavebuilddir": "test" 08:41:09 INFO - } 08:41:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:41:09 INFO - Running post-action listener: _resource_record_post_action 08:41:09 INFO - [mozharness: 2016-11-29 16:41:09.232807Z] Finished read-buildbot-config step (success) 08:41:09 INFO - [mozharness: 2016-11-29 16:41:09.232991Z] Running download-and-extract step. 08:41:09 INFO - Running pre-action listener: _resource_record_pre_action 08:41:09 INFO - Running main action method: download_and_extract 08:41:09 INFO - mkdir: /builds/slave/test/build/tests 08:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:09 INFO - trying https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:41:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:41:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:41:10 INFO - Downloaded 1199 bytes. 08:41:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:41:10 INFO - Using the following test package requirements: 08:41:10 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:41:10 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:41:10 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:41:10 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'jsshell-mac.zip'], 08:41:10 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:41:10 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:41:10 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:41:10 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:41:10 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:41:10 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:41:10 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:41:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:41:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:41:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:41:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:41:10 INFO - Http code: 200 08:41:10 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 08:41:10 INFO - x-amz-cf-id: OmoaAWCW6NrM6TLkeOfX6BTLSTqLwG1HNsJS2LM_zp7cBZpFqlT1ew== 08:41:10 INFO - x-amz-version-id: XQ8UQO49jPCyrT0b.7OSYSssMyOSIkRR 08:41:11 INFO - Expected file size: 16284843 08:41:11 INFO - Obtained file size: 16284843 08:41:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:41:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:41:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:41:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:41:18 INFO - Http code: 200 08:41:18 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:41:18 INFO - x-amz-cf-id: a_c3JhXc0ghEAOripM2S1EgsLUsp0hc0L_yEl89vOtftimz6UkxjhA== 08:41:18 INFO - x-amz-version-id: XQ8UQO49jPCyrT0b.7OSYSssMyOSIkRR 08:41:18 INFO - Expected file size: 16284843 08:41:18 INFO - Obtained file size: 16284843 08:41:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:41:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:41:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:41:25 INFO - Http code: 200 08:41:25 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:41:25 INFO - x-amz-cf-id: 5x9mcridMV-iLxTWzpgXLjqfoneSqPDfT-wFmM3hQjXcOYo_hngy6g== 08:41:25 INFO - x-amz-version-id: 6EZEvV98gkob4GgJpfgjd6Gw.QYrkrdC 08:41:26 INFO - Expected file size: 14409702 08:41:26 INFO - Obtained file size: 14409702 08:41: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')]} 08:41:26 INFO - trying https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:41:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:41:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:41:29 INFO - Downloaded 107588335 bytes. 08:41:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:41:29 INFO - mkdir: /builds/slave/test/properties 08:41:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:29 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:29 INFO - Contents: 08:41:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:41:29 INFO - Running post-action listener: _resource_record_post_action 08:41:29 INFO - Running post-action listener: set_extra_try_arguments 08:41:29 INFO - [mozharness: 2016-11-29 16:41:29.958418Z] Finished download-and-extract step (success) 08:41:29 INFO - [mozharness: 2016-11-29 16:41:29.958561Z] Running populate-webroot step. 08:41:29 INFO - Running pre-action listener: _resource_record_pre_action 08:41:29 INFO - Running main action method: populate_webroot 08:41:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:29 INFO - '--tppagecycles', 08:41:29 INFO - '7'], 08:41:29 INFO - 'tests': ['tsvgm']}}, 08:41:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['damp', 'tps']}, 08:41:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'tests': ['damp', 'tps']}, 08:41:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['dromaeo_dom']}, 08:41:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['basic_compositor_video']}, 08:41:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:41:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['a11yr', 08:41:29 INFO - 'ts_paint', 08:41:29 INFO - 'tpaint', 08:41:29 INFO - 'sessionrestore', 08:41:29 INFO - 'sessionrestore_no_auto_restore', 08:41:29 INFO - 'tabpaint']}, 08:41:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['tsvgx', 08:41:29 INFO - 'tsvgr_opacity', 08:41:29 INFO - 'tart', 08:41:29 INFO - 'tscrollx', 08:41:29 INFO - 'cart', 08:41:29 INFO - 'tsvg_static']}, 08:41:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:29 INFO - 'tsvgr_opacity', 08:41:29 INFO - 'tart', 08:41:29 INFO - 'tscrollx', 08:41:29 INFO - 'cart', 08:41:29 INFO - 'tsvg_static']}, 08:41:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'talos_options': ['--disable-e10s'], 08:41:29 INFO - 'tests': ['tp5o']}, 08:41:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:41:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'talos_options': ['--disable-e10s', 08:41:29 INFO - '--xperf_path', 08:41:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:29 INFO - 'tests': ['tp5n']}, 08:41:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:41:29 INFO - 'talos_options': ['--xperf_path', 08:41:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:29 INFO - 'tests': ['tp5n']}}, 08:41:29 INFO - 'talos.zip': {'path': '', 08:41:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:29 INFO - Running post-action listener: _resource_record_post_action 08:41:29 INFO - [mozharness: 2016-11-29 16:41:29.977883Z] Finished populate-webroot step (success) 08:41:29 INFO - [mozharness: 2016-11-29 16:41:29.977998Z] Running create-virtualenv step. 08:41:29 INFO - Running pre-action listener: _resource_record_pre_action 08:41:29 INFO - Running main action method: create_virtualenv 08:41:29 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41:29 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 08:41:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:30 INFO - Using real prefix '/tools/python27' 08:41:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:30 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:33 INFO - Installing pip.................done. 08:41:33 INFO - Return code: 0 08:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:41:33 INFO - Reading from file tmpfile_stdout 08:41:33 INFO - Output received: 08:41:33 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) 08:41:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:34 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:34 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 08:41:34 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 08:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:34 INFO - 'HOME': '/Users/cltbld', 08:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PAGER': '/bin/cat', 08:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:34 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - 'XPC_FLAGS': '0x0', 08:41:34 INFO - 'XPC_SERVICE_NAME': '0', 08:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 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 08:41:34 INFO - Cleaning up... 08:41:34 INFO - Return code: 0 08:41:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:34 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:34 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 08:41:34 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 08:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:34 INFO - 'HOME': '/Users/cltbld', 08:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PAGER': '/bin/cat', 08:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:34 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - 'XPC_FLAGS': '0x0', 08:41:34 INFO - 'XPC_SERVICE_NAME': '0', 08:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 INFO - Downloading/unpacking psutil>=3.1.1 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:37 INFO - Installing collected packages: psutil 08:41:37 INFO - Running setup.py install for psutil 08:41:38 INFO - building 'psutil._psutil_osx' extension 08:41:38 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 08:41:38 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 08:41:38 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 08:41:38 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 08:41:38 INFO - building 'psutil._psutil_posix' extension 08:41:38 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 08:41:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:41:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:41:38 INFO - ^ 08:41:38 INFO - 1 warning generated. 08:41:38 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 08:41:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:39 INFO - Successfully installed psutil 08:41:39 INFO - Cleaning up... 08:41:39 INFO - Return code: 0 08:41:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41:39 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')]} 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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')]} 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:39 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 08:41:39 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 08:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:39 INFO - 'HOME': '/Users/cltbld', 08:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:39 INFO - 'LOGNAME': 'cltbld', 08:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:39 INFO - 'MOZ_NO_REMOTE': '1', 08:41:39 INFO - 'NO_EM_RESTART': '1', 08:41:39 INFO - 'PAGER': '/bin/cat', 08:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:39 INFO - 'PWD': '/builds/slave/test', 08:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:39 INFO - 'SHELL': '/bin/bash', 08:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:39 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:39 INFO - 'USER': 'cltbld', 08:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:39 INFO - 'XPC_FLAGS': '0x0', 08:41:39 INFO - 'XPC_SERVICE_NAME': '0', 08:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:42 INFO - Installing collected packages: mozsystemmonitor 08:41:42 INFO - Running setup.py install for mozsystemmonitor 08:41:42 INFO - Successfully installed mozsystemmonitor 08:41:42 INFO - Cleaning up... 08:41:42 INFO - Return code: 0 08:41:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:42 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:42 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 08:41:42 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 08:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:42 INFO - 'HOME': '/Users/cltbld', 08:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:42 INFO - 'LOGNAME': 'cltbld', 08:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:42 INFO - 'MOZ_NO_REMOTE': '1', 08:41:42 INFO - 'NO_EM_RESTART': '1', 08:41:42 INFO - 'PAGER': '/bin/cat', 08:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:42 INFO - 'PWD': '/builds/slave/test', 08:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:42 INFO - 'SHELL': '/bin/bash', 08:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:42 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:42 INFO - 'USER': 'cltbld', 08:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:42 INFO - 'XPC_FLAGS': '0x0', 08:41:42 INFO - 'XPC_SERVICE_NAME': '0', 08:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:42 INFO - Downloading/unpacking jsonschema==2.5.1 08:41:42 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 08:41:42 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 08:41:42 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 08:41:42 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 08:41:42 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 08:41:42 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 08:41:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:45 INFO - warning: no files found matching '*.txt' 08:41:45 INFO - no previously-included directories found matching 'build' 08:41:45 INFO - no previously-included directories found matching 'dist' 08:41:45 INFO - no previously-included directories found matching '.git*' 08:41:45 INFO - Installing collected packages: jsonschema, functools32 08:41:45 INFO - Running setup.py install for functools32 08:41:45 INFO - warning: no files found matching '*.txt' 08:41:45 INFO - no previously-included directories found matching 'build' 08:41:45 INFO - no previously-included directories found matching 'dist' 08:41:45 INFO - no previously-included directories found matching '.git*' 08:41:45 INFO - Successfully installed jsonschema functools32 08:41:45 INFO - Cleaning up... 08:41:45 INFO - Return code: 0 08:41:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:41:45 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')]} 08:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:45 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')]} 08:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:45 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:45 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 08:41:45 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 08:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:45 INFO - 'HOME': '/Users/cltbld', 08:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:45 INFO - 'LOGNAME': 'cltbld', 08:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:45 INFO - 'MOZ_NO_REMOTE': '1', 08:41:45 INFO - 'NO_EM_RESTART': '1', 08:41:45 INFO - 'PAGER': '/bin/cat', 08:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:45 INFO - 'PWD': '/builds/slave/test', 08:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:45 INFO - 'SHELL': '/bin/bash', 08:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:45 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:45 INFO - 'USER': 'cltbld', 08:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:45 INFO - 'XPC_FLAGS': '0x0', 08:41:45 INFO - 'XPC_SERVICE_NAME': '0', 08:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:41:46 INFO - Cleaning up... 08:41:46 INFO - Return code: 0 08:41:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:46 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 08:41:46 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 08:41:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:46 INFO - 'HOME': '/Users/cltbld', 08:41:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:46 INFO - 'LOGNAME': 'cltbld', 08:41:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:46 INFO - 'MOZ_NO_REMOTE': '1', 08:41:46 INFO - 'NO_EM_RESTART': '1', 08:41:46 INFO - 'PAGER': '/bin/cat', 08:41:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:46 INFO - 'PWD': '/builds/slave/test', 08:41:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:46 INFO - 'SHELL': '/bin/bash', 08:41:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:46 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:46 INFO - 'USER': 'cltbld', 08:41:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:46 INFO - 'XPC_FLAGS': '0x0', 08:41:46 INFO - 'XPC_SERVICE_NAME': '0', 08:41:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:50 INFO - Downloading docopt-0.6.1.tar.gz 08:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:50 INFO - Running setup.py install for blobuploader 08:41:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:50 INFO - Running setup.py install for requests 08:41:50 INFO - Running setup.py install for docopt 08:41:51 INFO - Successfully installed blobuploader requests docopt 08:41:51 INFO - Cleaning up... 08:41:51 INFO - Return code: 0 08:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:51 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 08:41:51 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 08:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:51 INFO - 'HOME': '/Users/cltbld', 08:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:51 INFO - 'LOGNAME': 'cltbld', 08:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:51 INFO - 'MOZ_NO_REMOTE': '1', 08:41:51 INFO - 'NO_EM_RESTART': '1', 08:41:51 INFO - 'PAGER': '/bin/cat', 08:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:51 INFO - 'PWD': '/builds/slave/test', 08:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:51 INFO - 'SHELL': '/bin/bash', 08:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:51 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:51 INFO - 'USER': 'cltbld', 08:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:51 INFO - 'XPC_FLAGS': '0x0', 08:41:51 INFO - 'XPC_SERVICE_NAME': '0', 08:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3cnsme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-znx1Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-H4CzPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-z0tYZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2breK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-M3DHSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4A_AD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-96aqAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-WlqW3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-dyuAh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-tScivM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-btCs6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-mq0_BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-LS0WPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ENoQOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-sb33gL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-f7SQHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:53 INFO - Running setup.py install for manifestparser 08:41:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:53 INFO - Running setup.py install for mozcrash 08:41:53 INFO - Running setup.py install for mozdebug 08:41:53 INFO - Running setup.py install for mozdevice 08:41:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:41:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:53 INFO - Running setup.py install for mozfile 08:41:53 INFO - Running setup.py install for mozhttpd 08:41:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozinfo 08:41:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozInstall 08:41:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozleak 08:41:54 INFO - Running setup.py install for mozlog 08:41:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for moznetwork 08:41:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozprocess 08:41:55 INFO - Running setup.py install for mozprofile 08:41:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for mozrunner 08:41:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for mozscreenshot 08:41:55 INFO - Running setup.py install for moztest 08:41:55 INFO - Running setup.py install for mozversion 08:41:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:55 INFO - Cleaning up... 08:41:55 INFO - Return code: 0 08:41:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:55 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')]} 08:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:55 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')]} 08:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:55 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:55 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 08:41:55 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 08:41:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:41:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:55 INFO - 'HOME': '/Users/cltbld', 08:41:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:55 INFO - 'LOGNAME': 'cltbld', 08:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:55 INFO - 'MOZ_NO_REMOTE': '1', 08:41:55 INFO - 'NO_EM_RESTART': '1', 08:41:55 INFO - 'PAGER': '/bin/cat', 08:41:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:55 INFO - 'PWD': '/builds/slave/test', 08:41:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:55 INFO - 'SHELL': '/bin/bash', 08:41:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:41:55 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:55 INFO - 'USER': 'cltbld', 08:41:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:55 INFO - 'XPC_FLAGS': '0x0', 08:41:55 INFO - 'XPC_SERVICE_NAME': '0', 08:41:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-qcqcdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9ovqFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-UIFX5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-s70bvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-LceW8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3OuPCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-u4qog4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3zOit0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Snk9AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8Np1IV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-pL1Utl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9CzJ5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-d6U3TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-CHSHrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-dFsfoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-R9IbU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Cdw_tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:57 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)) 08:41:57 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)) 08:41:57 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)) 08:41:57 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)) 08:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:57 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 08:41:57 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 08:41:57 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 08:41:57 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 08:41:57 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 08:41:57 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 08:42:00 INFO - Downloading blessings-1.6.tar.gz 08:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:42:00 INFO - Installing collected packages: blessings 08:42:00 INFO - Running setup.py install for blessings 08:42:00 INFO - Successfully installed blessings 08:42:00 INFO - Cleaning up... 08:42:00 INFO - Return code: 0 08:42:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:42:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:01 INFO - Reading from file tmpfile_stdout 08:42:01 INFO - Current package versions: 08:42:01 INFO - blessings == 1.6 08:42:01 INFO - blobuploader == 1.2.4 08:42:01 INFO - docopt == 0.6.1 08:42:01 INFO - functools32 == 3.2.3-2 08:42:01 INFO - jsonschema == 2.5.1 08:42:01 INFO - manifestparser == 1.1 08:42:01 INFO - mozInstall == 1.12 08:42:01 INFO - mozcrash == 1.0 08:42:01 INFO - mozdebug == 0.1 08:42:01 INFO - mozdevice == 0.49 08:42:01 INFO - mozfile == 1.2 08:42:01 INFO - mozhttpd == 0.7 08:42:01 INFO - mozinfo == 0.9 08:42:01 INFO - mozleak == 0.1 08:42:01 INFO - mozlog == 3.4 08:42:01 INFO - moznetwork == 0.27 08:42:01 INFO - mozprocess == 0.23 08:42:01 INFO - mozprofile == 0.28 08:42:01 INFO - mozrunner == 6.13 08:42:01 INFO - mozscreenshot == 0.1 08:42:01 INFO - mozsystemmonitor == 0.3 08:42:01 INFO - moztest == 0.7 08:42:01 INFO - mozversion == 1.4 08:42:01 INFO - psutil == 3.1.1 08:42:01 INFO - requests == 1.2.3 08:42:01 INFO - wsgiref == 0.1.2 08:42:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:01 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:42:01 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 08:42:01 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 08:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:01 INFO - 'HOME': '/Users/cltbld', 08:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:01 INFO - 'LOGNAME': 'cltbld', 08:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:01 INFO - 'MOZ_NO_REMOTE': '1', 08:42:01 INFO - 'NO_EM_RESTART': '1', 08:42:01 INFO - 'PAGER': '/bin/cat', 08:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:01 INFO - 'PWD': '/builds/slave/test', 08:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:01 INFO - 'SHELL': '/bin/bash', 08:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:42:01 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:01 INFO - 'USER': 'cltbld', 08:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:01 INFO - 'XPC_FLAGS': '0x0', 08:42:01 INFO - 'XPC_SERVICE_NAME': '0', 08:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 INFO - Cleaning up... 08:42:01 INFO - Return code: 0 08:42:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:02 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 0x109e243f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef52576000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, '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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:42:02 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 08:42:02 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 08:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:02 INFO - 'HOME': '/Users/cltbld', 08:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:02 INFO - 'LOGNAME': 'cltbld', 08:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:02 INFO - 'MOZ_NO_REMOTE': '1', 08:42:02 INFO - 'NO_EM_RESTART': '1', 08:42:02 INFO - 'PAGER': '/bin/cat', 08:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:02 INFO - 'PWD': '/builds/slave/test', 08:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:02 INFO - 'SHELL': '/bin/bash', 08:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:42:02 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:02 INFO - 'USER': 'cltbld', 08:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:02 INFO - 'XPC_FLAGS': '0x0', 08:42:02 INFO - 'XPC_SERVICE_NAME': '0', 08:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:42:02 INFO - Cleaning up... 08:42:02 INFO - Return code: 0 08:42:02 INFO - Running post-action listener: _resource_record_post_action 08:42:02 INFO - Running post-action listener: _start_resource_monitoring 08:42:02 INFO - Starting resource monitoring. 08:42:02 INFO - [mozharness: 2016-11-29 16:42:02.562264Z] Finished create-virtualenv step (success) 08:42:02 INFO - [mozharness: 2016-11-29 16:42:02.563253Z] Running install step. 08:42:02 INFO - Running pre-action listener: _resource_record_pre_action 08:42:02 INFO - Running main action method: install 08:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:02 INFO - Reading from file tmpfile_stdout 08:42:02 INFO - Detecting whether we're running mozinstall >=1.0... 08:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:42:02 INFO - Reading from file tmpfile_stdout 08:42:02 INFO - Output received: 08:42:02 INFO - Usage: mozinstall [options] installer 08:42:02 INFO - Options: 08:42:02 INFO - -h, --help show this help message and exit 08:42:02 INFO - -d DEST, --destination=DEST 08:42:02 INFO - Directory to install application into. [default: 08:42:02 INFO - "/builds/slave/test"] 08:42:02 INFO - --app=APP Application being installed. [default: firefox] 08:42:02 INFO - mkdir: /builds/slave/test/build/application 08:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:42:35 INFO - Reading from file tmpfile_stdout 08:42:35 INFO - Output received: 08:42:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:42:35 INFO - Running post-action listener: _resource_record_post_action 08:42:35 INFO - [mozharness: 2016-11-29 16:42:35.578825Z] Finished install step (success) 08:42:35 INFO - [mozharness: 2016-11-29 16:42:35.578955Z] Running run-tests step. 08:42:35 INFO - Running pre-action listener: _resource_record_pre_action 08:42:35 INFO - Running main action method: run_tests 08:42:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:42:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:42:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:42:35 INFO - 2016-11-29 08:42:35.593 screenresolution[1629:7479] starting screenresolution argv=screenresolution get 08:42:35 INFO - 2016-11-29 08:42:35.595 screenresolution[1629:7479] Display 0: 1600x1200x32@60 08:42:35 INFO - 2016-11-29 08:42:35.602 screenresolution[1630:7481] starting screenresolution argv=screenresolution list 08:42:35 INFO - Available Modes on Display 0 08:42:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:42:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:42:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:42:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:42:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:42:35 INFO - Intel Iris: 08:42:35 INFO - Chipset Model: Intel Iris 08:42:35 INFO - Type: GPU 08:42:35 INFO - Bus: Built-In 08:42:35 INFO - VRAM (Dynamic, Max): 1536 MB 08:42:35 INFO - Vendor: Intel (0x8086) 08:42:35 INFO - Device ID: 0x0a2e 08:42:35 INFO - Revision ID: 0x0009 08:42:35 INFO - Displays: 08:42:35 INFO - Display: 08:42:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:42:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:42:35 INFO - Display Serial Number: 1600x1200 60 08:42:35 INFO - Main Display: Yes 08:42:35 INFO - Mirror: Off 08:42:35 INFO - Online: Yes 08:42:35 INFO - Rotation: Supported 08:42:35 INFO - Return code: 0 08:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:42:35 INFO - Python 2.7.3 08:42:35 INFO - Return code: 0 08:42:35 INFO - grabbing minidump binary from tooltool 08:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:35 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 0x109c3ad30>, '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 0x109aa1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:42:35 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 08:42:35 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 08:42:35 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 08:42:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:42:35 INFO - Return code: 0 08:42:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:42:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:42:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:42:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:42:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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 08:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --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 08:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1LRxTqLjWU/Render', 08:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:35 INFO - 'HOME': '/Users/cltbld', 08:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:35 INFO - 'LOGNAME': 'cltbld', 08:42:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:42:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:35 INFO - 'MOZ_NO_REMOTE': '1', 08:42:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:42:35 INFO - 'NO_EM_RESTART': '1', 08:42:35 INFO - 'PAGER': '/bin/cat', 08:42:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:35 INFO - 'PWD': '/builds/slave/test', 08:42:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:35 INFO - 'SHELL': '/bin/bash', 08:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N7xJp0OG1C/Listeners', 08:42:35 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:35 INFO - 'USER': 'cltbld', 08:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:35 INFO - 'XPC_FLAGS': '0x0', 08:42:35 INFO - 'XPC_SERVICE_NAME': '0', 08:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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 08:42:36 INFO - mozversion application_buildid: 20161129062926 08:42:36 INFO - mozversion application_changeset: 130bb29013a1d089dd533e50e170eabedce157d8 08:42:36 INFO - mozversion application_display_name: Nightly 08:42:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:42:36 INFO - mozversion application_name: Firefox 08:42:36 INFO - mozversion application_remotingname: firefox 08:42:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:42:36 INFO - mozversion application_vendor: Mozilla 08:42:36 INFO - mozversion application_version: 53.0a1 08:42:36 INFO - mozversion platform_buildid: 20161129062926 08:42:36 INFO - mozversion platform_changeset: 130bb29013a1d089dd533e50e170eabedce157d8 08:42:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:42:36 INFO - mozversion platform_version: 53.0a1 08:42:36 INFO - using testdate: 1480437756 08:42:36 INFO - actual date: 1480437756 08:42:36 INFO - starting webserver on 'localhost:49245' 08:42:36 INFO - SUITE-START | Running 2 tests 08:42:36 INFO - TEST-START | dromaeo_css 08:42:36 INFO - operating with platform_type : mac_ 08:42:36 INFO - Initialising browser for dromaeo_css test... 08:42:36 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpZlRBPs/profile http://localhost:49245/getInfo.html) 08:42:39 INFO - PROCESS | 1640 | 2016-11-29 08:42:39.435 plugin-container[1642:7654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:42:39 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:39 INFO - PROCESS | 1640 | 2016-11-29 08:42:39.438 plugin-container[1642:7654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9313, name = 'com.apple.CFPasteboardClient' 08:42:39 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:39 INFO - PROCESS | 1640 | 2016-11-29 08:42:39.438 plugin-container[1642:7654] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:39 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:42:39 INFO - PROCESS | 1640 | colorDepth:24 08:42:39 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:42:39 INFO - PROCESS | 1640 | __metrics 08:42:39 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 08:42:40 INFO - TEST-INFO | 1640: exit 0 08:42:40 INFO - Browser initialized. 08:42:40 INFO - Running cycle 1/1 for dromaeo_css test... 08:42:40 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpZlRBPs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:43 INFO - PROCESS | 1643 | 2016-11-29 08:42:43.185 plugin-container[1644:7774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:42:43 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:43 INFO - PROCESS | 1643 | 2016-11-29 08:42:43.186 plugin-container[1644:7774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:42:43 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:43 INFO - PROCESS | 1643 | 2016-11-29 08:42:43.186 plugin-container[1644:7774] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:52 INFO - PROCESS | 1643 | RSS: Main: 242941952 08:42:52 INFO - PROCESS | 1643 | 08:43:42 INFO - PROCESS | 1643 | 1480437822399 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] 08:45:56 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:45:58 INFO - PROCESS | 1643 | RSS: Main: 250167296 08:45:58 INFO - PROCESS | 1643 | 08:49:00 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 08:49:01 INFO - PROCESS | 1643 | RSS: Main: 260911104 08:49:01 INFO - PROCESS | 1643 | 08:52:03 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 08:52:04 INFO - PROCESS | 1643 | RSS: Main: 267206656 08:52:04 INFO - PROCESS | 1643 | 08:55:06 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 08:55:06 INFO - PROCESS | 1643 | RSS: Main: 265797632 08:55:06 INFO - PROCESS | 1643 | 08:58:09 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 08:58:10 INFO - PROCESS | 1643 | RSS: Main: 271474688 08:58:10 INFO - PROCESS | 1643 | 09:01:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:01:13 INFO - PROCESS | 1643 | RSS: Main: 274223104 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | __start_tp_report 09:01:13 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:01:13 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:01:13 INFO - PROCESS | 1643 | |i|pagename|runs| 09:01:13 INFO - PROCESS | 1643 | |0;dojo.html;2742.8491432971186;3214;3304.1409233599256;3335.3662804067226;3371.376295385355;5516.91724624131;5523.28197334346;5542.334919809628;5559.777608895644;5594.524465420434;5535.280413546233;5598.384177740445;5603.103503439451;5615.185798059279;5685.260916080903;4997.300377947072;5074.619403544731;5079.1365467870555;5157.174852023677;5172.534471897528;5130.127878260686;5172.793088276464;5185.222216667492;5209.062368773619;5243.082460569404;5007.5993920486635;5073.797048118071;5154.304168937183;5180.222966555009;5187.818426355078;4189.748615083105;5021.824236151717;5054.646174767768;5093.9812037592255;5179.741012949337;3642.1440961374055;3944.447777311179;4873.195922672755;5098.821541246039;5103.923441148386;5102.38771347437;5182.844514664528;5411.864703382407;5623.172468947609;5656.490915817596;5013.548780609726;5938.732757025944;6005.369436209179;6007.098935159717;6086.6348019119;5273.973630131824;5645.068563686987;6125.632272693312;6151.446369826737;6157.63054216744;5622.128570071645;5634.408387119375;5658.943410565924;5670.773169073233;5686.943130568683;6129.938700613026;6141.07883817431;6159;6266.49868010558;6275;6036.456718895321;6054.576179667469;6062.090686396988;6071.428571428589;6098.969505152446;5853.912191317134;5980.671063091485;6053.152558641794;6115.605440958429;6137.0794380842335;6066.150738896558;6076.939230607726;6094.695265236721;6106.2061931948565;6138.938610613926;6129.540284478682;6137.67682805338;6153.4154255345675;6158.876822463615;6203.193584833943;4637.559431853969;4784.9951510183255;4850.1269771441475;4863.1732605457155;4878.317035615017;4262.7655478948955;4285.164392943346;4298;4319.049809042006;4349.043210493686;5512.145617429305;5723.54211663066;5736.168255602997;5737.51231145349;5757.798477053283;6175.2898416681755;6181.887260293101;6189.50483961282;6203.751849925952;6217.098520714561;5556.361018482926;5586.46928541796;5657.57568182388;5677.574181936371;5710.2576665033275;4325.416068830667;4507.864764057083;5268.736563171826;5281.55106815925;5299;5051.924221136685;6207.193064901534;6214.658193799384;6227.346128656516;6229.408206220402;5395.028894798974;5880.764769409176;6120.843333897766;6138.07928810682;6139.706800529493;6166.352532983972;6233.812985610439;6239.188905442264;6247.5939063960695;6262.2172228471445;6106.9083963739695;6110.114033465212;6119.265688117454;6128.8774224516155;6134.723937422916;5867;6093.695315234309;6121.265448146251;6121.5139442231075;6131.315499379051;5495.972520137374;5943.435373639497;5961.940380596312;5976.760929562769;6054.788082417271;5997.220361352996;6005.39946005396;6013.128096426081;6014.0678194879865;6032.125341825498;5611.915821262766;5673.347565030074;5832.825015249549;5869.618474799125;5875.118732190205;5839.854003649943;5931.08068249422;5942.554308426799;5953.970230148995;5959.582829201914;5502.477264659851;5682.460166284196;5688.829335119953;5744.396838331915;5790.478856902899 09:01:13 INFO - PROCESS | 1643 | |1;ext.html;441.3996964128784;504.8258350868953;530.700154412757;532.2362409941734;532.9706866122362;27681.58477622835;30901.690983090164;35264.11839704002;37005.51977920884;39341.40987885184;39976;40465;40521.797391013075;40546.59453405465;40576.391354129635;8043.316318112968;8304.792380190513;8528.061913189544;9156.359054866145;9437.764055898622;10928.890711092907;11183.161262905314;11221.214514983954;11342.489587968561;11353.489092990836;11251.493682784254;11282.605108821193;11303.152263580223;11317.094632429427;11341.149413793957;11121.110311175087;11178.27341222822;11217.66347009591;11227.438628068563;11245.269057511276;11074.778504429867;11090.722731931686;11144.27562208462;11180.720481987933;11200.943995280053;38717.22565548701;38831.89344852405;38912.22175556501;38979.415308770105;39000;3288.161518812711;3741.7942013189263;3905.882823515299;3922.37242041272;3960.1881614269296;29542.409151816842;30072.248193795112;30146.397072058655;30224.697753022407;30280.394392112255;2941.1176647005814;3080.1067690369764;3184.697453741891;3186.553882456458;3194;408252;420351;421763;422980.9999999969;425528;56987.145192821314;57737;57897.842043158904;57915.552111197554;58029;60194.29125689354;61528.61720581617;61775;61971.69014154901;62201.68899155566;14950.70098598022;14962.177080260473;15222.238888055554;15427.768583471257;15447.61380965485;9504.239660827117;9618.086281803218;11595.014423774073;11798;11811.88188118125;18328.725069123975;18462.538436539195;18498.907505462656;18568.164432600566;18611;7656.502327348704;7822.374210063182;7830.882536761953;7852.332551733052;7906.288434040964;848.8556945319187;1002.293383164867;1008.2488546033252;1012.4735137728487;1030.1398332392387;27889.442211155663;28648.997285094923;28934.855325723238;29592.55611165834;29869.103926882228;1021.1422405179536;1083.2092572422018;1102.7408558988632;1117.7596816684452;1158.368689064462;12326.198797078161;15106.697866042618;15229.847701522844;15524.534263972098;15572.532824015298;468783;553361;562669;563640;567990;54636;55181.724091379285;55226.44773552213;55236.44763552393;55304.72347638236;1061.8991195836384;1198.9389390389395;1323.4573824731813;1347.5148946379295;1350.5138150265896;22591.548169036854;28357.149285521467;29094.709052910046;29716;29904.85047574748;46230;47785.76107119581;48114.75942620265;48532.51467485421;48762;356912;413645;431010;466457.670846588;488113;20465.590688185854;20717.689234661793;25507;25901.352466188495;25999.740002600487;30408.239794004443;36454;43866.780666097744;44280.557194427645;45076.32385514286;35245;35474.290514190085;37183.256334873695;37858.81070594629;38413.80793096017;28120.85939570289;30104.24739381533;34427.82786086154;35866.565337387256;37016.07459813418;36401;36938.630613693516;37184.6281537192;37242.62757372392;37412.812935935144;26898;30120.397592048474;30818.767249309778;32331.35337293288;33475.4978675325;21229.46926326854;24948;25020.874895625406;25451.10921117752;25562.488750225264 09:01:13 INFO - PROCESS | 1643 | |2;jquery.html;369.9593044765077;371.47621853187;377.8904117805836;383.0538569732759;399.28328650073115;18112.637747245048;19307.420777376665;19562.21751129955;19947.800521994777;20147.496312592193;17716.734248986242;17866.374676886313;18254.726179107325;18290.268389264445;18427.53931151722;5519.889602207953;5532.474414930595;5744.448998770329;5813.331466881299;5842.516930304625;5543.36251331096;5721.399253078429;5723.370429252779;5725.083986562151;5742.569307301948;5479.561635069204;5587.497125258747;5641.887162256752;5665;5700.201971723962;5543.223948647173;5639.548836093103;5645.040343141676;5675.659460432366;5676.091825307952;4932.186189278765;4963.131451995886;4970.254461830718;4985.002999400134;5216.60875434344;12423.068269879705;12731.872681273162;12777.10560260782;12817.23096614206;18233.452996410128;2063.504758857878;2194.626913424722;2264.184893438361;2436.707595088583;2489.3776555861036;9470.715878523655;9865.309428340019;15785.842141578552;16170;16314.184290785413;1896.1656870976865;1906.8379187769056;1913.9330123445613;1927.6530224559574;1976.8122028407279;121753;126164;129536.7046329534;129666.35166824106;131011.68988310186;17588;19845;20080.39758807238;20777.688334674993;21029.89485052565;32904.83547582247;33174;33371.49942750861;33390;33426.66573334236;8764.255038321686;9562.091601297865;9919.553620087114;9960.551775170135;10195.337303075275;7989.360851131895;8220.458649243084;8281.710140145065;8288.544130072783;8431.957840210882;8292.626831792584;9331.253499720005;9504.572294246775;9512.714618561455;9522.333436659368;3143.9939219450016;3505.8772942947385;3536.487209354629;3796.6962642988497;3851.807409629507;708.9964550177319;837.8240569480356;900.7793090692822;905.5110240470073;924.1036194890945;18892.960887151337;19819.603607927762;20047.799522004592;20061.899690501454;20066.799332006784;699.0667458942369;781.4764108047622;854.5043874552745;897.1297841094129;903.5437104262305;7963.800904977306;8534.87197692035;8680.479171249744;8738.432001919855;9666.178374838077;528292;532476;545141;563676;565175;97388.51305743426;97701.51149244208;119581.0104747371;119799;121558;813.4875028731861;874.1980447103738;918.554501066982;940.3041749105613;951.2627713521938;17583.472495825146;17721.734173987657;17760.20079096418;17786;18046.187921543824;49692.00615987588;51332.71668208325;51466;51488.742556286976;53185.734071329396;69792.95310570244;72109.6394518024;74351;75675.62162189154;76257.85613215917;7673.271039251262;7794.415418843496;7852.646630901738;7977;8088.9595552021865;8947.373683842068;11174.161937854531;11540.94229528847;11770.705732356417;12177.939110304393;9390.342676012613;10465.633702820365;10483.580656773644;10512.789744205225;10712.35725856451;7721.420893432903;9931.304808663324;10174.593016279561;10482.423466709404;10529.36823790575;10743;10754.677359679223;10880.564777409132;10960.232783705063;10987.945060274647;6295.748170073146;7648.541087534766;7852.725154619561;8081.595920204084;8109.148539403278;9337.626494940127;10382.896171038194;10522.526486308288;10648.520816563117;10687.946560267459 09:01:13 INFO - PROCESS | 1643 | |3;mootools.html;1420.6945506716056;1424.0814674534925;1440.7982882396473;1618.3607475047356;1808.0327025041606;7484.962575187116;7724.922750772491;8069.112397636255;8275.462094963823;8463.788405289872;6199.411055949678;7270.636468176596;7581.734639287626;7932.881006784903;8396.748097557067;2079.07481170879;2131.435169680036;2251.5271792923504;2288.8855557222155;2291.564602725477;2277.9658305125436;2316.9768302317016;2317.8725170115636;2326.395137264315;2332.3819187915215;2252.087904398713;2266.1048885690143;2290.03818396274;2293.5068960173558;2306.988465057681;2257.9661305080435;2259.0737797502943;2286.7141607299086;2288.187693368855;2289.221664634024;2190.759016508183;2211.292386436341;2217.22397160994;2218.556288742242;2227.187076717003;8320.417570770049;8381.83236335276;8430.283425908805;8457.830843383097;8473.576321183979;3870.9419358709642;4983.25251212321;5004.049230646166;5013.824516141917;5021.895183059721;3185.5221716742444;3267.2322004329203;3527.3121741260466;3527.770694904849;3542.3977923753023;1025.6256466389789;1045.3100953370738;1063.4682658670665;1079.4548752879823;1082.5507414423005;10658.413787241698;10700.839487407695;10727.409992450412;10762.677119686343;10825.025747682668;4459;4532.660050496226;4673.135469938073;4680.180968330528;4693.929591056137;9308.534573271445;9397.624095036259;9401.24790016797;9410.38832475887;9447.385919915183;5115.667481613683;5121.8719532011255;5144.7942082316285;5158.252053452228;5162.148245539519;4078.0824314528995;4630.398048253705;4660.4407471103;4711.081339138835;4740.241561350167;3396.352768907078;3534.4698295255916;3731.9253614928093;3797.5632802228097;3828.0429892526868;1250.5748045664518;1251.6495381293253;1259.118616968126;1268.0743057568027;1268.4989429175423;796.5897562755185;861.1388611388611;898.6360523987795;920.3189639666598;920.4431319052011;5706.71466426677;5719.113537401709;5738.483536481654;5781.306243250753;5967.850803729942;908.1917093786535;917.6191880369705;935.4293880732747;938.6057855700623;968.1238479176357;3810.8666196682984;3818.866339678098;3835.7314987950576;3928.5678575356687;3934.9803250984132;9671.081247281498;10153.543090560943;10159.44123073238;10178.389296642225;10178.847317290247;10024.997500249916;10102.141317988053;10131.784185897739;10146.289759716896;10155.898441015495;7408.925910740932;7754.495957762728;7818.609069546386;7861.882071768928;7987.720429784929;5728.885422291614;5732.942670573241;5830.183774271521;5841.32824725145;5940.405959404025;7535.962320188364;9008.684696035869;9279.953600231956;9396.248300136109;9451.291153163404;9470.479123648265;9520.666776662783;9610.903890961281;9611.615535378785;9629.614815407305;6610.338966103352;6816.829579260559;6943.201531823905;7076.22161562238;7221.4222862171955;6969.745445819801;7702.306792388712;7756.457048006811;7939.801504962422;7985.6007199638625;7011.088558487364;7093.113360829896;7143;7150.177729561167;7238.131424229126;6756.797296081126;6910;6975.476839236976;6998.860022799618;7050.330218629222;6623.834404139935;6684.398404143651;6759.594424334556;6794.66026698673;6813.557118787263;4057.959420405758;4804.2073057945045;4850.951490485191;4852.733099679552;4943.505649435172;4018.618231268025;4778.856634300976;4837.661363704648;4929.408470983505;5014.9498505015945 09:01:13 INFO - PROCESS | 1643 | |4;prototype.html;255.66507874684166;288.1557037879015;300.12513523080224;309.18375488709796;310.5977758802351;5163.664361816479;5261.684298942067;5329.4936980986795;5378.46215378462;5389.380221274555;4403.757793321366;4424.513303536608;5052.949470505294;5271;5299.28459657946;4082.122343696105;4084.83660653575;4092.4270602115726;4095.467589213413;4117.341225403936;3382.560267165265;3781.073636958949;4083.4691490106243;4106.117184805266;4110.362893751473;3284.178955261185;3430.5934566827605;3869.4582758413844;3957.168994511156;3987.9601203987877;3155.447796635591;3161.668024857403;3379.695827375536;3967.404889266604;3994.2410941920944;2888.62447881774;3468.3063387322636;3479.739019573542;3493.336266109431;3502.984134600988;4967.55292023716;5017.8243761468175;5086.237064440364;5116.462771409023;5141.203113517411;3790.7346485746007;4453.799579018952;4584.381108550337;4592.311153326994;4617.676762626617;5028.421731500887;5200.6619569727845;5257.290265814105;5570.888582228333;5682.57935516121;3483.651634836521;3564.7861128332124;3588.4258518636893;3793.6396042376205;3819.491300936115;5033.748312584393;5736;5811.854703632443;5822.010258256149;5826.7377967991115;3858.7877666728036;3859.6526312632;3859.899928520358;3887.8833634990997;3907.8276517044774;3928.155446578999;3930.0371409004406;3945.408188771707;3954.5452272988973;3955.3473676843;3815.6184381562175;3871.1870507193244;3959.8416063357145;4498.707584007029;4575.58819706228;5451.127819548932;5557.36090349615;5613.354464236583;5622;5663.178839068312;1108.3571528513444;1120.6862078618;1214.651736572404;1215.6778453709774;1236.771197328497;840.5836166060208;851.646566674823;865.2342676731138;868.8957325121025;870.1429092344036;4179.707420480538;4978.502149784992;4987.301777751045;5007.6494645375205;5025.623078269145;4886.828960986349;5178.818741344035;5237.8930542720545;5449.4005659377435;5453.028099685681;4591.931121033187;4931.235658472942;4950.504949505021;4984.850454486299;5000.324956130876;2370.838289238261;2733.630959820399;2733.8359698418158;2822.2803185188054;2829.9717002829707;5143.12566863634;5606.355269144019;5621.409751976065;5627.677495788486;5672.744726487318;5008.720672427886;5337.172738225581;5448.673079615157;5526.392096869341;5546.336099170314;5649.604527683023;5652.971735141298;5657.377688454267;5688.943110569007;5694.608264528908;4584.404027476407;4828.927566086441;5178.922316165336;5242.895142097061;5289.523942845009;4793.736344501086;5109.948900510947;5360.195970604284;5362.570994320367;5376.919346209732;4654.371659826015;5596.4123767003875;5599.300087489064;5628.183913332462;5628.493435590816;3474.496198051319;3632.7093832493865;3643.289558536166;3648.562172539312;3650.6896913762625;4458.398116254264;5039.546440820343;5434.228543442793;5467.507924286674;5470.343558772973;5016.964736334611;5284.339457567804;5613.129964855454;5618.3258009037545;5619.887602247851;5371.194320851903;5578.414266501959;5630.971845140748;5639.915401268902;5642.285680040738;4196.454460920061;5311.044012077863;5487.981610114501;5513.624216388988;5519.475649813261;5012;5358.46415358461;5538.1786567268455;5592.888142237214;5639.039504260296;4599.954000459952;5347.524109328967;5483.067878460592;5486.899901555608;5496.422875598005 09:01:13 INFO - PROCESS | 1643 | |5;yui.html;684.7945616315105;691.9031335613018;743.2047708951424;764.8011517005577;775.3719487215354;13856.58430247093;14086.718265634681;14092.36584353704;14115.505957291483;14131;13922;14066.085704429204;14082.64793380166;14093.57719268421;14106.788398174036;638.4541217259247;653.7025653327725;660.2868901586287;795.9283664470197;812.0134037144868;766.8888011238367;816.6284340625031;822.268181318627;822.5229366967145;823.0329362998483;749.7938067031555;753.1489416958829;847.8558645030329;875.4528419737663;881.607684580362;844.5692696724715;845.0197770586121;849.8087930215682;877.3463769491706;877.3463769491769;641.035241960846;649.1366482578206;649.327945576331;649.3960616626536;650.7657243392375;13678.521251756141;14131.505397311148;14151.716965660733;14334.211618360985;15038.624034399118;14236.093832419301;14353.210573418457;14427.422903083863;14457.421703131862;14634.19504937646;9695.951520242425;11001.669949901516;11090.445477726162;11282.266652667551;11322.377269250188;3669.1560940983723;3698.7225958052986;3704.25914817035;3766.472693822867;3823.3500304947947;115306;115378.00085988852;116319;116581;117518;10737.409442480583;10807.297525660806;10865.891341086646;10887.074598659045;11071.501782419811;18220.36228731988;22610.208642697427;22625.54748905013;22644.433889152573;22784.974676139613;7413.481056326005;7549.962250188823;7572.280633339824;7603.2776886195725;7694.422918281151;3604.333198358312;4042.3734321180264;4152.730072545335;4154.293770059097;4171.624553790173;3322.5182348559324;3494.4408894576745;3521.436570148764;3521.9823900880842;3529.6470352964498;608.2761513798565;619.2723549828933;619.3404024713666;631.3497097989078;639.1563136659564;505.6334157735686;629.1129507394552;629.6442509981846;638.4860187549068;644.5005121031238;8671.869921951176;8809.867851982226;8952.507612081397;8989.46063236208;9200.81598368029;499.72764843160564;571.4085921071671;635.8569321902628;642.932492088324;650.4828661214251;4179.519355274182;4456.799444025027;4506.797194126272;4569.245159107662;4591.14058806184;98625;98753;109441;109505;110831;81627;81635.59182204051;81718.77421838677;96697.06605867844;101096;79676.20323796803;80034.19965800268;80593.19406805857;80968.99999999882;83076.58461707774;9565.378250413702;9668.516574171123;9863.704088877344;9884;9970.40177589347;44569.21723131039;48571.5142848567;49693.50919472422;50031;50354;59890.10164847618;63037.42406439876;69719.95420068805;77089.61455192688;77644.83532747126;12436.626901192978;12453.875461245272;12461.75076498483;12517.812232816334;12732.427040783372;14334.283285835458;14382.928085359506;14400.711985760436;14444.422223111378;14572.271386430424;13237.80143297832;13461.461541538229;13467.393967271692;13502.932485337511;14027;11163.944180279372;11298.943505282421;13283.734325313633;13359.599212023655;13685.863141368856;11037.448127593749;11610.896964788331;11945.761084778429;12022.819657704966;12265.57070502528;8993.640254390013;9776.80446391054;10663.733406664895;10765.946170269099;10882.29265097766;8029.919700803151;9325.580348884452;10506.264561480928;11266.324020558794;11345.829614202254 09:01:13 INFO - PROCESS | 1643 | __end_tp_report 09:01:13 INFO - PROCESS | 1643 | __start_cc_report 09:01:13 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,364 09:01:13 INFO - PROCESS | 1643 | __end_cc_report 09:01:13 INFO - PROCESS | 1643 | __startTimestamp1480438873048__endTimestamp 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:01:13 INFO - PROCESS | 1643 | Number of tests: 6 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5565.10 Median:5723.54 stddev:691.21 (12.1%) stddev-sans-first:656.92 09:01:13 INFO - PROCESS | 1643 | Values: 2742.8 3214.0 3304.1 3335.4 3371.4 5516.9 5523.3 5542.3 5559.8 5594.5 5535.3 5598.4 5603.1 5615.2 5685.3 4997.3 5074.6 5079.1 5157.2 5172.5 5130.1 5172.8 5185.2 5209.1 5243.1 5007.6 5073.8 5154.3 5180.2 5187.8 4189.7 5021.8 5054.6 5094.0 5179.7 3642.1 3944.4 4873.2 5098.8 5103.9 5102.4 5182.8 5411.9 5623.2 5656.5 5013.5 5938.7 6005.4 6007.1 6086.6 5274.0 5645.1 6125.6 6151.4 6157.6 5622.1 5634.4 5658.9 5670.8 5686.9 6129.9 6141.1 6159.0 6266.5 6275.0 6036.5 6054.6 6062.1 6071.4 6099.0 5853.9 5980.7 6053.2 6115.6 6137.1 6066.2 6076.9 6094.7 6106.2 6138.9 6129.5 6137.7 6153.4 6158.9 6203.2 4637.6 4785.0 4850.1 4863.2 4878.3 4262.8 4285.2 4298.0 4319.0 4349.0 5512.1 5723.5 5736.2 5737.5 5757.8 6175.3 6181.9 6189.5 6203.8 6217.1 5556.4 5586.5 5657.6 5677.6 5710.3 4325.4 4507.9 5268.7 5281.6 5299.0 5051.9 6207.2 6214.7 6227.3 6229.4 5395.0 5880.8 6120.8 6138.1 6139.7 6166.4 6233.8 6239.2 6247.6 6262.2 6106.9 6110.1 6119.3 6128.9 6134.7 5867.0 6093.7 6121.3 6121.5 6131.3 5496.0 5943.4 5961.9 5976.8 6054.8 5997.2 6005.4 6013.1 6014.1 6032.1 5611.9 5673.3 5832.8 5869.6 5875.1 5839.9 5931.1 5942.6 5954.0 5959.6 5502.5 5682.5 5688.8 5744.4 5790.5 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:60392.44 Median:17809.76 stddev:124847.56 (701.0%) stddev-sans-first:125116.65 09:01:13 INFO - PROCESS | 1643 | Values: 441.4 504.8 530.7 532.2 533.0 27681.6 30901.7 35264.1 37005.5 39341.4 39976.0 40465.0 40521.8 40546.6 40576.4 8043.3 8304.8 8528.1 9156.4 9437.8 10928.9 11183.2 11221.2 11342.5 11353.5 11251.5 11282.6 11303.2 11317.1 11341.1 11121.1 11178.3 11217.7 11227.4 11245.3 11074.8 11090.7 11144.3 11180.7 11200.9 38717.2 38831.9 38912.2 38979.4 39000.0 3288.2 3741.8 3905.9 3922.4 3960.2 29542.4 30072.2 30146.4 30224.7 30280.4 2941.1 3080.1 3184.7 3186.6 3194.0 408252.0 420351.0 421763.0 422981.0 425528.0 56987.1 57737.0 57897.8 57915.6 58029.0 60194.3 61528.6 61775.0 61971.7 62201.7 14950.7 14962.2 15222.2 15427.8 15447.6 9504.2 9618.1 11595.0 11798.0 11811.9 18328.7 18462.5 18498.9 18568.2 18611.0 7656.5 7822.4 7830.9 7852.3 7906.3 848.9 1002.3 1008.2 1012.5 1030.1 27889.4 28649.0 28934.9 29592.6 29869.1 1021.1 1083.2 1102.7 1117.8 1158.4 12326.2 15106.7 15229.8 15524.5 15572.5 468783.0 553361.0 562669.0 563640.0 567990.0 54636.0 55181.7 55226.4 55236.4 55304.7 1061.9 1198.9 1323.5 1347.5 1350.5 22591.5 28357.1 29094.7 29716.0 29904.9 46230.0 47785.8 48114.8 48532.5 48762.0 356912.0 413645.0 431010.0 466457.7 488113.0 20465.6 20717.7 25507.0 25901.4 25999.7 30408.2 36454.0 43866.8 44280.6 45076.3 35245.0 35474.3 37183.3 37858.8 38413.8 28120.9 30104.2 34427.8 35866.6 37016.1 36401.0 36938.6 37184.6 37242.6 37412.8 26898.0 30120.4 30818.8 32331.4 33475.5 21229.5 24948.0 25020.9 25451.1 25562.5 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33569.28 Median:4977.63 stddev:91567.04 (1839.6%) stddev-sans-first:91789.98 09:01:13 INFO - PROCESS | 1643 | Values: 370.0 371.5 377.9 383.1 399.3 18112.6 19307.4 19562.2 19947.8 20147.5 17716.7 17866.4 18254.7 18290.3 18427.5 5519.9 5532.5 5744.4 5813.3 5842.5 5543.4 5721.4 5723.4 5725.1 5742.6 5479.6 5587.5 5641.9 5665.0 5700.2 5543.2 5639.5 5645.0 5675.7 5676.1 4932.2 4963.1 4970.3 4985.0 5216.6 12423.1 12731.9 12777.1 12817.2 18233.5 2063.5 2194.6 2264.2 2436.7 2489.4 9470.7 9865.3 15785.8 16170.0 16314.2 1896.2 1906.8 1913.9 1927.7 1976.8 121753.0 126164.0 129536.7 129666.4 131011.7 17588.0 19845.0 20080.4 20777.7 21029.9 32904.8 33174.0 33371.5 33390.0 33426.7 8764.3 9562.1 9919.6 9960.6 10195.3 7989.4 8220.5 8281.7 8288.5 8432.0 8292.6 9331.3 9504.6 9512.7 9522.3 3144.0 3505.9 3536.5 3796.7 3851.8 709.0 837.8 900.8 905.5 924.1 18893.0 19819.6 20047.8 20061.9 20066.8 699.1 781.5 854.5 897.1 903.5 7963.8 8534.9 8680.5 8738.4 9666.2 528292.0 532476.0 545141.0 563676.0 565175.0 97388.5 97701.5 119581.0 119799.0 121558.0 813.5 874.2 918.6 940.3 951.3 17583.5 17721.7 17760.2 17786.0 18046.2 49692.0 51332.7 51466.0 51488.7 53185.7 69793.0 72109.6 74351.0 75675.6 76257.9 7673.3 7794.4 7852.6 7977.0 8089.0 8947.4 11174.2 11540.9 11770.7 12177.9 9390.3 10465.6 10483.6 10512.8 10712.4 7721.4 9931.3 10174.6 10482.4 10529.4 10743.0 10754.7 10880.6 10960.2 10987.9 6295.7 7648.5 7852.7 8081.6 8109.1 9337.6 10382.9 10522.5 10648.5 10687.9 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5385.65 Median:5008.94 stddev:2960.46 (59.1%) stddev-sans-first:2953.77 09:01:13 INFO - PROCESS | 1643 | Values: 1420.7 1424.1 1440.8 1618.4 1808.0 7485.0 7724.9 8069.1 8275.5 8463.8 6199.4 7270.6 7581.7 7932.9 8396.7 2079.1 2131.4 2251.5 2288.9 2291.6 2278.0 2317.0 2317.9 2326.4 2332.4 2252.1 2266.1 2290.0 2293.5 2307.0 2258.0 2259.1 2286.7 2288.2 2289.2 2190.8 2211.3 2217.2 2218.6 2227.2 8320.4 8381.8 8430.3 8457.8 8473.6 3870.9 4983.3 5004.0 5013.8 5021.9 3185.5 3267.2 3527.3 3527.8 3542.4 1025.6 1045.3 1063.5 1079.5 1082.6 10658.4 10700.8 10727.4 10762.7 10825.0 4459.0 4532.7 4673.1 4680.2 4693.9 9308.5 9397.6 9401.2 9410.4 9447.4 5115.7 5121.9 5144.8 5158.3 5162.1 4078.1 4630.4 4660.4 4711.1 4740.2 3396.4 3534.5 3731.9 3797.6 3828.0 1250.6 1251.6 1259.1 1268.1 1268.5 796.6 861.1 898.6 920.3 920.4 5706.7 5719.1 5738.5 5781.3 5967.9 908.2 917.6 935.4 938.6 968.1 3810.9 3818.9 3835.7 3928.6 3935.0 9671.1 10153.5 10159.4 10178.4 10178.8 10025.0 10102.1 10131.8 10146.3 10155.9 7408.9 7754.5 7818.6 7861.9 7987.7 5728.9 5732.9 5830.2 5841.3 5940.4 7536.0 9008.7 9280.0 9396.2 9451.3 9470.5 9520.7 9610.9 9611.6 9629.6 6610.3 6816.8 6943.2 7076.2 7221.4 6969.7 7702.3 7756.5 7939.8 7985.6 7011.1 7093.1 7143.0 7150.2 7238.1 6756.8 6910.0 6975.5 6998.9 7050.3 6623.8 6684.4 6759.6 6794.7 6813.6 4058.0 4804.2 4851.0 4852.7 4943.5 4018.6 4778.9 4837.7 4929.4 5014.9 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4399.22 Median:5010.36 stddev:1369.35 (27.3%) stddev-sans-first:1337.40 09:01:13 INFO - PROCESS | 1643 | Values: 255.7 288.2 300.1 309.2 310.6 5163.7 5261.7 5329.5 5378.5 5389.4 4403.8 4424.5 5052.9 5271.0 5299.3 4082.1 4084.8 4092.4 4095.5 4117.3 3382.6 3781.1 4083.5 4106.1 4110.4 3284.2 3430.6 3869.5 3957.2 3988.0 3155.4 3161.7 3379.7 3967.4 3994.2 2888.6 3468.3 3479.7 3493.3 3503.0 4967.6 5017.8 5086.2 5116.5 5141.2 3790.7 4453.8 4584.4 4592.3 4617.7 5028.4 5200.7 5257.3 5570.9 5682.6 3483.7 3564.8 3588.4 3793.6 3819.5 5033.7 5736.0 5811.9 5822.0 5826.7 3858.8 3859.7 3859.9 3887.9 3907.8 3928.2 3930.0 3945.4 3954.5 3955.3 3815.6 3871.2 3959.8 4498.7 4575.6 5451.1 5557.4 5613.4 5622.0 5663.2 1108.4 1120.7 1214.7 1215.7 1236.8 840.6 851.6 865.2 868.9 870.1 4179.7 4978.5 4987.3 5007.6 5025.6 4886.8 5178.8 5237.9 5449.4 5453.0 4591.9 4931.2 4950.5 4984.9 5000.3 2370.8 2733.6 2733.8 2822.3 2830.0 5143.1 5606.4 5621.4 5627.7 5672.7 5008.7 5337.2 5448.7 5526.4 5546.3 5649.6 5653.0 5657.4 5688.9 5694.6 4584.4 4828.9 5178.9 5242.9 5289.5 4793.7 5109.9 5360.2 5362.6 5376.9 4654.4 5596.4 5599.3 5628.2 5628.5 3474.5 3632.7 3643.3 3648.6 3650.7 4458.4 5039.5 5434.2 5467.5 5470.3 5017.0 5284.3 5613.1 5618.3 5619.9 5371.2 5578.4 5631.0 5639.9 5642.3 4196.5 5311.0 5488.0 5513.6 5519.5 5012.0 5358.5 5538.2 5592.9 5639.0 4600.0 5347.5 5483.1 5486.9 5496.4 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20640.33 Median:24513.01 stddev:30771.52 (125.5%) stddev-sans-first:30821.36 09:01:13 INFO - PROCESS | 1643 | Values: 684.8 691.9 743.2 764.8 775.4 13856.6 14086.7 14092.4 14115.5 14131.0 13922.0 14066.1 14082.6 14093.6 14106.8 638.5 653.7 660.3 795.9 812.0 766.9 816.6 822.3 822.5 823.0 749.8 753.1 847.9 875.5 881.6 844.6 845.0 849.8 877.3 877.3 641.0 649.1 649.3 649.4 650.8 13678.5 14131.5 14151.7 14334.2 15038.6 14236.1 14353.2 14427.4 14457.4 14634.2 9696.0 11001.7 11090.4 11282.3 11322.4 3669.2 3698.7 3704.3 3766.5 3823.4 115306.0 115378.0 116319.0 116581.0 117518.0 10737.4 10807.3 10865.9 10887.1 11071.5 18220.4 22610.2 22625.5 22644.4 22785.0 7413.5 7550.0 7572.3 7603.3 7694.4 3604.3 4042.4 4152.7 4154.3 4171.6 3322.5 3494.4 3521.4 3522.0 3529.6 608.3 619.3 619.3 631.3 639.2 505.6 629.1 629.6 638.5 644.5 8671.9 8809.9 8952.5 8989.5 9200.8 499.7 571.4 635.9 642.9 650.5 4179.5 4456.8 4506.8 4569.2 4591.1 98625.0 98753.0 109441.0 109505.0 110831.0 81627.0 81635.6 81718.8 96697.1 101096.0 79676.2 80034.2 80593.2 80969.0 83076.6 9565.4 9668.5 9863.7 9884.0 9970.4 44569.2 48571.5 49693.5 50031.0 50354.0 59890.1 63037.4 69720.0 77089.6 77644.8 12436.6 12453.9 12461.8 12517.8 12732.4 14334.3 14382.9 14400.7 14444.4 14572.3 13237.8 13461.5 13467.4 13502.9 14027.0 11163.9 11298.9 13283.7 13359.6 13685.9 11037.4 11610.9 11945.8 12022.8 12265.6 8993.6 9776.8 10663.7 10765.9 10882.3 8029.9 9325.6 10506.3 11266.3 11345.8 09:01:13 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:01:13 INFO - PROCESS | 1643 | 09:01:13 INFO - TEST-INFO | 1643: exit 0 09:01:13 INFO - TEST-OK | dromaeo_css | took 1117549ms 09:01:13 INFO - TEST-START | kraken 09:01:13 INFO - operating with platform_type : mac_ 09:01:13 INFO - Initialising browser for kraken test... 09:01:13 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpMS6VUH/profile http://localhost:49245/getInfo.html) 09:01:16 INFO - PROCESS | 1655 | 2016-11-29 09:01:16.757 plugin-container[1656:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 09:01:16 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:16 INFO - PROCESS | 1655 | 2016-11-29 09:01:16.759 plugin-container[1656:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:01:16 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:16 INFO - PROCESS | 1655 | 2016-11-29 09:01:16.759 plugin-container[1656:10223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:16 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:01:16 INFO - PROCESS | 1655 | colorDepth:24 09:01:16 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:01:16 INFO - PROCESS | 1655 | __metrics 09:01:16 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 09:01:17 INFO - TEST-INFO | 1655: exit 0 09:01:17 INFO - Browser initialized. 09:01:17 INFO - Running cycle 1/1 for kraken test... 09:01:17 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpMS6VUH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:20 INFO - PROCESS | 1657 | 2016-11-29 09:01:20.480 plugin-container[1658:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:01:20 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:20 INFO - PROCESS | 1657 | 2016-11-29 09:01:20.481 plugin-container[1658:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:01:20 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:20 INFO - PROCESS | 1657 | 2016-11-29 09:01:20.481 plugin-container[1658:10344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:29 INFO - PROCESS | 1657 | RSS: Main: 242610176 09:01:29 INFO - PROCESS | 1657 | 09:01:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 09:01:33 INFO - PROCESS | 1657 | RSS: Main: 255016960 09:01:33 INFO - PROCESS | 1657 | 09:01:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 09:01:37 INFO - PROCESS | 1657 | RSS: Main: 262823936 09:01:37 INFO - PROCESS | 1657 | 09:01:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 09:01:41 INFO - PROCESS | 1657 | RSS: Main: 265732096 09:01:41 INFO - PROCESS | 1657 | 09:01:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 09:01:43 INFO - PROCESS | 1657 | RSS: Main: 266805248 09:01:43 INFO - PROCESS | 1657 | 09:01:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:01:46 INFO - PROCESS | 1657 | RSS: Main: 267149312 09:01:46 INFO - PROCESS | 1657 | 09:01:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 09:01:50 INFO - PROCESS | 1657 | RSS: Main: 274546688 09:01:50 INFO - PROCESS | 1657 | 09:01:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:54 INFO - PROCESS | 1657 | RSS: Main: 273346560 09:01:54 INFO - PROCESS | 1657 | 09:01:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 09:01:57 INFO - PROCESS | 1657 | RSS: Main: 273444864 09:01:57 INFO - PROCESS | 1657 | 09:01:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:02:00 INFO - PROCESS | 1657 | RSS: Main: 272723968 09:02:00 INFO - PROCESS | 1657 | 09:02:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:02:02 INFO - PROCESS | 1657 | RSS: Main: 272748544 09:02:02 INFO - PROCESS | 1657 | 09:02:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:02:05 INFO - PROCESS | 1657 | RSS: Main: 272826368 09:02:05 INFO - PROCESS | 1657 | 09:02:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:02:09 INFO - PROCESS | 1657 | RSS: Main: 273076224 09:02:09 INFO - PROCESS | 1657 | 09:02:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:02:12 INFO - PROCESS | 1657 | RSS: Main: 272932864 09:02:12 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 09:02:14 INFO - PROCESS | 1657 | RSS: Main: 274780160 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | __start_tp_report 09:02:14 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:02:14 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:02:14 INFO - PROCESS | 1657 | |i|pagename|runs| 09:02:14 INFO - PROCESS | 1657 | |0;ai-astar;108;121;120;118;119;127;171;124;118;119 09:02:14 INFO - PROCESS | 1657 | |1;audio-beat-detection;185;135;107;182;109;149;147;158;142;119 09:02:14 INFO - PROCESS | 1657 | |2;audio-dft;201;230;263;199;197;204;197;197;260;216 09:02:14 INFO - PROCESS | 1657 | |3;audio-fft;104;72;83;103;61;60;63;52;93;82 09:02:14 INFO - PROCESS | 1657 | |4;audio-oscillator;95;71;84;74;55;90;114;88;102;113 09:02:14 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;124;132;90;94;90;86;91;88;90;87 09:02:14 INFO - PROCESS | 1657 | |6;imaging-darkroom;99;154;152;108;161;152;99;109;118;97 09:02:14 INFO - PROCESS | 1657 | |7;imaging-desaturate;92;97;91;109;144;119;92;118;136;125 09:02:14 INFO - PROCESS | 1657 | |8;json-parse-financial;57;59;58;58;58;59;58;59;60;56 09:02:14 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;69;74;70;78;74;69;71;72;103;105 09:02:14 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;87;90;92;90;92;88;89;86;99 09:02:14 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;151;150;163;149;155;150;153;161;190;165 09:02:14 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;178;176;176;174;174;178;176;175;175;171 09:02:14 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;71;70;76;91;90;96;91;92;74 09:02:14 INFO - PROCESS | 1657 | __end_tp_report 09:02:14 INFO - PROCESS | 1657 | __start_cc_report 09:02:14 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,960 09:02:14 INFO - PROCESS | 1657 | __end_cc_report 09:02:14 INFO - PROCESS | 1657 | __startTimestamp1480438934814__endTimestamp 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:02:14 INFO - PROCESS | 1657 | Number of tests: 14 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.50 Median:120.50 stddev:17.06 (14.2%) stddev-sans-first:17.01 09:02:14 INFO - PROCESS | 1657 | Values: 108.0 121.0 120.0 118.0 119.0 127.0 171.0 124.0 118.0 119.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.00 stddev:27.19 (18.4%) stddev-sans-first:24.30 09:02:14 INFO - PROCESS | 1657 | Values: 185.0 135.0 107.0 182.0 109.0 149.0 147.0 158.0 142.0 119.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:216.40 Median:210.00 stddev:25.99 (12.4%) stddev-sans-first:26.96 09:02:14 INFO - PROCESS | 1657 | Values: 201.0 230.0 263.0 199.0 197.0 204.0 197.0 197.0 260.0 216.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:77.30 Median:67.50 stddev:18.60 (27.5%) stddev-sans-first:17.03 09:02:14 INFO - PROCESS | 1657 | Values: 104.0 72.0 83.0 103.0 61.0 60.0 63.0 52.0 93.0 82.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:79.00 stddev:18.67 (23.6%) stddev-sans-first:19.66 09:02:14 INFO - PROCESS | 1657 | Values: 95.0 71.0 84.0 74.0 55.0 90.0 114.0 88.0 102.0 113.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:90.00 stddev:16.49 (18.3%) stddev-sans-first:14.36 09:02:14 INFO - PROCESS | 1657 | Values: 124.0 132.0 90.0 94.0 90.0 86.0 91.0 88.0 90.0 87.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:124.90 Median:157.50 stddev:26.51 (16.8%) stddev-sans-first:26.41 09:02:14 INFO - PROCESS | 1657 | Values: 99.0 154.0 152.0 108.0 161.0 152.0 99.0 109.0 118.0 97.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:117.50 stddev:19.23 (16.4%) stddev-sans-first:18.94 09:02:14 INFO - PROCESS | 1657 | Values: 92.0 97.0 91.0 109.0 144.0 119.0 92.0 118.0 136.0 125.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.50 stddev:1.14 (1.9%) stddev-sans-first:1.12 09:02:14 INFO - PROCESS | 1657 | Values: 57.0 59.0 58.0 58.0 58.0 59.0 58.0 59.0 60.0 56.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:71.50 stddev:13.72 (19.2%) stddev-sans-first:14.12 09:02:14 INFO - PROCESS | 1657 | Values: 69.0 74.0 70.0 78.0 74.0 69.0 71.0 72.0 103.0 105.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:90.00 stddev:3.65 (4.1%) stddev-sans-first:3.84 09:02:14 INFO - PROCESS | 1657 | Values: 89.0 87.0 90.0 92.0 90.0 92.0 88.0 89.0 86.0 99.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:158.00 stddev:12.45 (7.9%) stddev-sans-first:12.89 09:02:14 INFO - PROCESS | 1657 | Values: 151.0 150.0 163.0 149.0 155.0 150.0 153.0 161.0 190.0 165.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:176.00 stddev:2.06 (1.2%) stddev-sans-first:1.94 09:02:14 INFO - PROCESS | 1657 | Values: 178.0 176.0 176.0 174.0 174.0 178.0 176.0 175.0 175.0 171.0 09:02:14 INFO - PROCESS | 1657 | 09:02:14 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.90 Median:90.50 stddev:10.97 (12.1%) stddev-sans-first:10.42 09:02:14 INFO - PROCESS | 1657 | Values: 68.0 71.0 70.0 76.0 91.0 90.0 96.0 91.0 92.0 74.0 09:02:14 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:02:14 INFO - PROCESS | 1657 | 09:02:15 INFO - TEST-INFO | 1657: exit 0 09:02:15 INFO - TEST-OK | kraken | took 62140ms 09:02:15 INFO - SUITE-END | took 1179s 09:02:15 INFO - Completed test suite (00:19:39) 09:02:15 INFO - javascript benchmark 09:02:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7107.481076040805, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2742.8491432971186, 3214.0, 3304.1409233599256, 3335.3662804067226, 3371.376295385355, 5516.91724624131, 5523.28197334346, 5542.334919809628, 5559.777608895644, 5594.524465420434, 5535.280413546233, 5598.384177740445, 5603.103503439451, 5615.185798059279, 5685.260916080903, 4997.300377947072, 5074.619403544731, 5079.1365467870555, 5157.174852023677, 5172.534471897528, 5130.127878260686, 5172.793088276464, 5185.222216667492, 5209.062368773619, 5243.082460569404, 5007.5993920486635, 5073.797048118071, 5154.304168937183, 5180.222966555009, 5187.818426355078, 4189.748615083105, 5021.824236151717, 5054.646174767768, 5093.9812037592255, 5179.741012949337, 3642.1440961374055, 3944.447777311179, 4873.195922672755, 5098.821541246039, 5103.923441148386, 5102.38771347437, 5182.844514664528, 5411.864703382407, 5623.172468947609, 5656.490915817596, 5013.548780609726, 5938.732757025944, 6005.369436209179, 6007.098935159717, 6086.6348019119, 5273.973630131824, 5645.068563686987, 6125.632272693312, 6151.446369826737, 6157.63054216744, 5622.128570071645, 5634.408387119375, 5658.943410565924, 5670.773169073233, 5686.943130568683, 6129.938700613026, 6141.07883817431, 6159.0, 6266.49868010558, 6275.0, 6036.456718895321, 6054.576179667469, 6062.090686396988, 6071.428571428589, 6098.969505152446, 5853.912191317134, 5980.671063091485, 6053.152558641794, 6115.605440958429, 6137.0794380842335, 6066.150738896558, 6076.939230607726, 6094.695265236721, 6106.2061931948565, 6138.938610613926, 6129.540284478682, 6137.67682805338, 6153.4154255345675, 6158.876822463615, 6203.193584833943, 4637.559431853969, 4784.9951510183255, 4850.1269771441475, 4863.1732605457155, 4878.317035615017, 4262.7655478948955, 4285.164392943346, 4298.0, 4319.049809042006, 4349.043210493686, 5512.145617429305, 5723.54211663066, 5736.168255602997, 5737.51231145349, 5757.798477053283, 6175.2898416681755, 6181.887260293101, 6189.50483961282, 6203.751849925952, 6217.098520714561, 5556.361018482926, 5586.46928541796, 5657.57568182388, 5677.574181936371, 5710.2576665033275, 4325.416068830667, 4507.864764057083, 5268.736563171826, 5281.55106815925, 5299.0, 5051.924221136685, 6207.193064901534, 6214.658193799384, 6227.346128656516, 6229.408206220402, 5395.028894798974, 5880.764769409176, 6120.843333897766, 6138.07928810682, 6139.706800529493, 6166.352532983972, 6233.812985610439, 6239.188905442264, 6247.5939063960695, 6262.2172228471445, 6106.9083963739695, 6110.114033465212, 6119.265688117454, 6128.8774224516155, 6134.723937422916, 5867.0, 6093.695315234309, 6121.265448146251, 6121.5139442231075, 6131.315499379051, 5495.972520137374, 5943.435373639497, 5961.940380596312, 5976.760929562769, 6054.788082417271, 5997.220361352996, 6005.39946005396, 6013.128096426081, 6014.0678194879865, 6032.125341825498, 5611.915821262766, 5673.347565030074, 5832.825015249549, 5869.618474799125, 5875.118732190205, 5839.854003649943, 5931.08068249422, 5942.554308426799, 5953.970230148995, 5959.582829201914, 5502.477264659851, 5682.460166284196, 5688.829335119953, 5744.396838331915, 5790.478856902899], "value": 5519.235276852588, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.3996964128784, 504.8258350868953, 530.700154412757, 532.2362409941734, 532.9706866122362, 27681.58477622835, 30901.690983090164, 35264.11839704002, 37005.51977920884, 39341.40987885184, 39976.0, 40465.0, 40521.797391013075, 40546.59453405465, 40576.391354129635, 8043.316318112968, 8304.792380190513, 8528.061913189544, 9156.359054866145, 9437.764055898622, 10928.890711092907, 11183.161262905314, 11221.214514983954, 11342.489587968561, 11353.489092990836, 11251.493682784254, 11282.605108821193, 11303.152263580223, 11317.094632429427, 11341.149413793957, 11121.110311175087, 11178.27341222822, 11217.66347009591, 11227.438628068563, 11245.269057511276, 11074.778504429867, 11090.722731931686, 11144.27562208462, 11180.720481987933, 11200.943995280053, 38717.22565548701, 38831.89344852405, 38912.22175556501, 38979.415308770105, 39000.0, 3288.161518812711, 3741.7942013189263, 3905.882823515299, 3922.37242041272, 3960.1881614269296, 29542.409151816842, 30072.248193795112, 30146.397072058655, 30224.697753022407, 30280.394392112255, 2941.1176647005814, 3080.1067690369764, 3184.697453741891, 3186.553882456458, 3194.0, 408252.0, 420351.0, 421763.0, 422980.9999999969, 425528.0, 56987.145192821314, 57737.0, 57897.842043158904, 57915.552111197554, 58029.0, 60194.29125689354, 61528.61720581617, 61775.0, 61971.69014154901, 62201.68899155566, 14950.70098598022, 14962.177080260473, 15222.238888055554, 15427.768583471257, 15447.61380965485, 9504.239660827117, 9618.086281803218, 11595.014423774073, 11798.0, 11811.88188118125, 18328.725069123975, 18462.538436539195, 18498.907505462656, 18568.164432600566, 18611.0, 7656.502327348704, 7822.374210063182, 7830.882536761953, 7852.332551733052, 7906.288434040964, 848.8556945319187, 1002.293383164867, 1008.2488546033252, 1012.4735137728487, 1030.1398332392387, 27889.442211155663, 28648.997285094923, 28934.855325723238, 29592.55611165834, 29869.103926882228, 1021.1422405179536, 1083.2092572422018, 1102.7408558988632, 1117.7596816684452, 1158.368689064462, 12326.198797078161, 15106.697866042618, 15229.847701522844, 15524.534263972098, 15572.532824015298, 468783.0, 553361.0, 562669.0, 563640.0, 567990.0, 54636.0, 55181.724091379285, 55226.44773552213, 55236.44763552393, 55304.72347638236, 1061.8991195836384, 1198.9389390389395, 1323.4573824731813, 1347.5148946379295, 1350.5138150265896, 22591.548169036854, 28357.149285521467, 29094.709052910046, 29716.0, 29904.85047574748, 46230.0, 47785.76107119581, 48114.75942620265, 48532.51467485421, 48762.0, 356912.0, 413645.0, 431010.0, 466457.670846588, 488113.0, 20465.590688185854, 20717.689234661793, 25507.0, 25901.352466188495, 25999.740002600487, 30408.239794004443, 36454.0, 43866.780666097744, 44280.557194427645, 45076.32385514286, 35245.0, 35474.290514190085, 37183.256334873695, 37858.81070594629, 38413.80793096017, 28120.85939570289, 30104.24739381533, 34427.82786086154, 35866.565337387256, 37016.07459813418, 36401.0, 36938.630613693516, 37184.6281537192, 37242.62757372392, 37412.812935935144, 26898.0, 30120.397592048474, 30818.767249309778, 32331.35337293288, 33475.4978675325, 21229.46926326854, 24948.0, 25020.874895625406, 25451.10921117752, 25562.488750225264], "value": 19176.487707768334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.9593044765077, 371.47621853187, 377.8904117805836, 383.0538569732759, 399.28328650073115, 18112.637747245048, 19307.420777376665, 19562.21751129955, 19947.800521994777, 20147.496312592193, 17716.734248986242, 17866.374676886313, 18254.726179107325, 18290.268389264445, 18427.53931151722, 5519.889602207953, 5532.474414930595, 5744.448998770329, 5813.331466881299, 5842.516930304625, 5543.36251331096, 5721.399253078429, 5723.370429252779, 5725.083986562151, 5742.569307301948, 5479.561635069204, 5587.497125258747, 5641.887162256752, 5665.0, 5700.201971723962, 5543.223948647173, 5639.548836093103, 5645.040343141676, 5675.659460432366, 5676.091825307952, 4932.186189278765, 4963.131451995886, 4970.254461830718, 4985.002999400134, 5216.60875434344, 12423.068269879705, 12731.872681273162, 12777.10560260782, 12817.23096614206, 18233.452996410128, 2063.504758857878, 2194.626913424722, 2264.184893438361, 2436.707595088583, 2489.3776555861036, 9470.715878523655, 9865.309428340019, 15785.842141578552, 16170.0, 16314.184290785413, 1896.1656870976865, 1906.8379187769056, 1913.9330123445613, 1927.6530224559574, 1976.8122028407279, 121753.0, 126164.0, 129536.7046329534, 129666.35166824106, 131011.68988310186, 17588.0, 19845.0, 20080.39758807238, 20777.688334674993, 21029.89485052565, 32904.83547582247, 33174.0, 33371.49942750861, 33390.0, 33426.66573334236, 8764.255038321686, 9562.091601297865, 9919.553620087114, 9960.551775170135, 10195.337303075275, 7989.360851131895, 8220.458649243084, 8281.710140145065, 8288.544130072783, 8431.957840210882, 8292.626831792584, 9331.253499720005, 9504.572294246775, 9512.714618561455, 9522.333436659368, 3143.9939219450016, 3505.8772942947385, 3536.487209354629, 3796.6962642988497, 3851.807409629507, 708.9964550177319, 837.8240569480356, 900.7793090692822, 905.5110240470073, 924.1036194890945, 18892.960887151337, 19819.603607927762, 20047.799522004592, 20061.899690501454, 20066.799332006784, 699.0667458942369, 781.4764108047622, 854.5043874552745, 897.1297841094129, 903.5437104262305, 7963.800904977306, 8534.87197692035, 8680.479171249744, 8738.432001919855, 9666.178374838077, 528292.0, 532476.0, 545141.0, 563676.0, 565175.0, 97388.51305743426, 97701.51149244208, 119581.0104747371, 119799.0, 121558.0, 813.4875028731861, 874.1980447103738, 918.554501066982, 940.3041749105613, 951.2627713521938, 17583.472495825146, 17721.734173987657, 17760.20079096418, 17786.0, 18046.187921543824, 49692.00615987588, 51332.71668208325, 51466.0, 51488.742556286976, 53185.734071329396, 69792.95310570244, 72109.6394518024, 74351.0, 75675.62162189154, 76257.85613215917, 7673.271039251262, 7794.415418843496, 7852.646630901738, 7977.0, 8088.9595552021865, 8947.373683842068, 11174.161937854531, 11540.94229528847, 11770.705732356417, 12177.939110304393, 9390.342676012613, 10465.633702820365, 10483.580656773644, 10512.789744205225, 10712.35725856451, 7721.420893432903, 9931.304808663324, 10174.593016279561, 10482.423466709404, 10529.36823790575, 10743.0, 10754.677359679223, 10880.564777409132, 10960.232783705063, 10987.945060274647, 6295.748170073146, 7648.541087534766, 7852.725154619561, 8081.595920204084, 8109.148539403278, 9337.626494940127, 10382.896171038194, 10522.526486308288, 10648.520816563117, 10687.946560267459], "value": 9765.282230435048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.6945506716056, 1424.0814674534925, 1440.7982882396473, 1618.3607475047356, 1808.0327025041606, 7484.962575187116, 7724.922750772491, 8069.112397636255, 8275.462094963823, 8463.788405289872, 6199.411055949678, 7270.636468176596, 7581.734639287626, 7932.881006784903, 8396.748097557067, 2079.07481170879, 2131.435169680036, 2251.5271792923504, 2288.8855557222155, 2291.564602725477, 2277.9658305125436, 2316.9768302317016, 2317.8725170115636, 2326.395137264315, 2332.3819187915215, 2252.087904398713, 2266.1048885690143, 2290.03818396274, 2293.5068960173558, 2306.988465057681, 2257.9661305080435, 2259.0737797502943, 2286.7141607299086, 2288.187693368855, 2289.221664634024, 2190.759016508183, 2211.292386436341, 2217.22397160994, 2218.556288742242, 2227.187076717003, 8320.417570770049, 8381.83236335276, 8430.283425908805, 8457.830843383097, 8473.576321183979, 3870.9419358709642, 4983.25251212321, 5004.049230646166, 5013.824516141917, 5021.895183059721, 3185.5221716742444, 3267.2322004329203, 3527.3121741260466, 3527.770694904849, 3542.3977923753023, 1025.6256466389789, 1045.3100953370738, 1063.4682658670665, 1079.4548752879823, 1082.5507414423005, 10658.413787241698, 10700.839487407695, 10727.409992450412, 10762.677119686343, 10825.025747682668, 4459.0, 4532.660050496226, 4673.135469938073, 4680.180968330528, 4693.929591056137, 9308.534573271445, 9397.624095036259, 9401.24790016797, 9410.38832475887, 9447.385919915183, 5115.667481613683, 5121.8719532011255, 5144.7942082316285, 5158.252053452228, 5162.148245539519, 4078.0824314528995, 4630.398048253705, 4660.4407471103, 4711.081339138835, 4740.241561350167, 3396.352768907078, 3534.4698295255916, 3731.9253614928093, 3797.5632802228097, 3828.0429892526868, 1250.5748045664518, 1251.6495381293253, 1259.118616968126, 1268.0743057568027, 1268.4989429175423, 796.5897562755185, 861.1388611388611, 898.6360523987795, 920.3189639666598, 920.4431319052011, 5706.71466426677, 5719.113537401709, 5738.483536481654, 5781.306243250753, 5967.850803729942, 908.1917093786535, 917.6191880369705, 935.4293880732747, 938.6057855700623, 968.1238479176357, 3810.8666196682984, 3818.866339678098, 3835.7314987950576, 3928.5678575356687, 3934.9803250984132, 9671.081247281498, 10153.543090560943, 10159.44123073238, 10178.389296642225, 10178.847317290247, 10024.997500249916, 10102.141317988053, 10131.784185897739, 10146.289759716896, 10155.898441015495, 7408.925910740932, 7754.495957762728, 7818.609069546386, 7861.882071768928, 7987.720429784929, 5728.885422291614, 5732.942670573241, 5830.183774271521, 5841.32824725145, 5940.405959404025, 7535.962320188364, 9008.684696035869, 9279.953600231956, 9396.248300136109, 9451.291153163404, 9470.479123648265, 9520.666776662783, 9610.903890961281, 9611.615535378785, 9629.614815407305, 6610.338966103352, 6816.829579260559, 6943.201531823905, 7076.22161562238, 7221.4222862171955, 6969.745445819801, 7702.306792388712, 7756.457048006811, 7939.801504962422, 7985.6007199638625, 7011.088558487364, 7093.113360829896, 7143.0, 7150.177729561167, 7238.131424229126, 6756.797296081126, 6910.0, 6975.476839236976, 6998.860022799618, 7050.330218629222, 6623.834404139935, 6684.398404143651, 6759.594424334556, 6794.66026698673, 6813.557118787263, 4057.959420405758, 4804.2073057945045, 4850.951490485191, 4852.733099679552, 4943.505649435172, 4018.618231268025, 4778.856634300976, 4837.661363704648, 4929.408470983505, 5014.9498505015945], "value": 4368.130551802486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.66507874684166, 288.1557037879015, 300.12513523080224, 309.18375488709796, 310.5977758802351, 5163.664361816479, 5261.684298942067, 5329.4936980986795, 5378.46215378462, 5389.380221274555, 4403.757793321366, 4424.513303536608, 5052.949470505294, 5271.0, 5299.28459657946, 4082.122343696105, 4084.83660653575, 4092.4270602115726, 4095.467589213413, 4117.341225403936, 3382.560267165265, 3781.073636958949, 4083.4691490106243, 4106.117184805266, 4110.362893751473, 3284.178955261185, 3430.5934566827605, 3869.4582758413844, 3957.168994511156, 3987.9601203987877, 3155.447796635591, 3161.668024857403, 3379.695827375536, 3967.404889266604, 3994.2410941920944, 2888.62447881774, 3468.3063387322636, 3479.739019573542, 3493.336266109431, 3502.984134600988, 4967.55292023716, 5017.8243761468175, 5086.237064440364, 5116.462771409023, 5141.203113517411, 3790.7346485746007, 4453.799579018952, 4584.381108550337, 4592.311153326994, 4617.676762626617, 5028.421731500887, 5200.6619569727845, 5257.290265814105, 5570.888582228333, 5682.57935516121, 3483.651634836521, 3564.7861128332124, 3588.4258518636893, 3793.6396042376205, 3819.491300936115, 5033.748312584393, 5736.0, 5811.854703632443, 5822.010258256149, 5826.7377967991115, 3858.7877666728036, 3859.6526312632, 3859.899928520358, 3887.8833634990997, 3907.8276517044774, 3928.155446578999, 3930.0371409004406, 3945.408188771707, 3954.5452272988973, 3955.3473676843, 3815.6184381562175, 3871.1870507193244, 3959.8416063357145, 4498.707584007029, 4575.58819706228, 5451.127819548932, 5557.36090349615, 5613.354464236583, 5622.0, 5663.178839068312, 1108.3571528513444, 1120.6862078618, 1214.651736572404, 1215.6778453709774, 1236.771197328497, 840.5836166060208, 851.646566674823, 865.2342676731138, 868.8957325121025, 870.1429092344036, 4179.707420480538, 4978.502149784992, 4987.301777751045, 5007.6494645375205, 5025.623078269145, 4886.828960986349, 5178.818741344035, 5237.8930542720545, 5449.4005659377435, 5453.028099685681, 4591.931121033187, 4931.235658472942, 4950.504949505021, 4984.850454486299, 5000.324956130876, 2370.838289238261, 2733.630959820399, 2733.8359698418158, 2822.2803185188054, 2829.9717002829707, 5143.12566863634, 5606.355269144019, 5621.409751976065, 5627.677495788486, 5672.744726487318, 5008.720672427886, 5337.172738225581, 5448.673079615157, 5526.392096869341, 5546.336099170314, 5649.604527683023, 5652.971735141298, 5657.377688454267, 5688.943110569007, 5694.608264528908, 4584.404027476407, 4828.927566086441, 5178.922316165336, 5242.895142097061, 5289.523942845009, 4793.736344501086, 5109.948900510947, 5360.195970604284, 5362.570994320367, 5376.919346209732, 4654.371659826015, 5596.4123767003875, 5599.300087489064, 5628.183913332462, 5628.493435590816, 3474.496198051319, 3632.7093832493865, 3643.289558536166, 3648.562172539312, 3650.6896913762625, 4458.398116254264, 5039.546440820343, 5434.228543442793, 5467.507924286674, 5470.343558772973, 5016.964736334611, 5284.339457567804, 5613.129964855454, 5618.3258009037545, 5619.887602247851, 5371.194320851903, 5578.414266501959, 5630.971845140748, 5639.915401268902, 5642.285680040738, 4196.454460920061, 5311.044012077863, 5487.981610114501, 5513.624216388988, 5519.475649813261, 5012.0, 5358.46415358461, 5538.1786567268455, 5592.888142237214, 5639.039504260296, 4599.954000459952, 5347.524109328967, 5483.067878460592, 5486.899901555608, 5496.422875598005], "value": 3956.2792382158023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.7945616315105, 691.9031335613018, 743.2047708951424, 764.8011517005577, 775.3719487215354, 13856.58430247093, 14086.718265634681, 14092.36584353704, 14115.505957291483, 14131.0, 13922.0, 14066.085704429204, 14082.64793380166, 14093.57719268421, 14106.788398174036, 638.4541217259247, 653.7025653327725, 660.2868901586287, 795.9283664470197, 812.0134037144868, 766.8888011238367, 816.6284340625031, 822.268181318627, 822.5229366967145, 823.0329362998483, 749.7938067031555, 753.1489416958829, 847.8558645030329, 875.4528419737663, 881.607684580362, 844.5692696724715, 845.0197770586121, 849.8087930215682, 877.3463769491706, 877.3463769491769, 641.035241960846, 649.1366482578206, 649.327945576331, 649.3960616626536, 650.7657243392375, 13678.521251756141, 14131.505397311148, 14151.716965660733, 14334.211618360985, 15038.624034399118, 14236.093832419301, 14353.210573418457, 14427.422903083863, 14457.421703131862, 14634.19504937646, 9695.951520242425, 11001.669949901516, 11090.445477726162, 11282.266652667551, 11322.377269250188, 3669.1560940983723, 3698.7225958052986, 3704.25914817035, 3766.472693822867, 3823.3500304947947, 115306.0, 115378.00085988852, 116319.0, 116581.0, 117518.0, 10737.409442480583, 10807.297525660806, 10865.891341086646, 10887.074598659045, 11071.501782419811, 18220.36228731988, 22610.208642697427, 22625.54748905013, 22644.433889152573, 22784.974676139613, 7413.481056326005, 7549.962250188823, 7572.280633339824, 7603.2776886195725, 7694.422918281151, 3604.333198358312, 4042.3734321180264, 4152.730072545335, 4154.293770059097, 4171.624553790173, 3322.5182348559324, 3494.4408894576745, 3521.436570148764, 3521.9823900880842, 3529.6470352964498, 608.2761513798565, 619.2723549828933, 619.3404024713666, 631.3497097989078, 639.1563136659564, 505.6334157735686, 629.1129507394552, 629.6442509981846, 638.4860187549068, 644.5005121031238, 8671.869921951176, 8809.867851982226, 8952.507612081397, 8989.46063236208, 9200.81598368029, 499.72764843160564, 571.4085921071671, 635.8569321902628, 642.932492088324, 650.4828661214251, 4179.519355274182, 4456.799444025027, 4506.797194126272, 4569.245159107662, 4591.14058806184, 98625.0, 98753.0, 109441.0, 109505.0, 110831.0, 81627.0, 81635.59182204051, 81718.77421838677, 96697.06605867844, 101096.0, 79676.20323796803, 80034.19965800268, 80593.19406805857, 80968.99999999882, 83076.58461707774, 9565.378250413702, 9668.516574171123, 9863.704088877344, 9884.0, 9970.40177589347, 44569.21723131039, 48571.5142848567, 49693.50919472422, 50031.0, 50354.0, 59890.10164847618, 63037.42406439876, 69719.95420068805, 77089.61455192688, 77644.83532747126, 12436.626901192978, 12453.875461245272, 12461.75076498483, 12517.812232816334, 12732.427040783372, 14334.283285835458, 14382.928085359506, 14400.711985760436, 14444.422223111378, 14572.271386430424, 13237.80143297832, 13461.461541538229, 13467.393967271692, 13502.932485337511, 14027.0, 11163.944180279372, 11298.943505282421, 13283.734325313633, 13359.599212023655, 13685.863141368856, 11037.448127593749, 11610.896964788331, 11945.761084778429, 12022.819657704966, 12265.57070502528, 8993.640254390013, 9776.80446391054, 10663.733406664895, 10765.946170269099, 10882.29265097766, 8029.919700803151, 9325.580348884452, 10506.264561480928, 11266.324020558794, 11345.829614202254], "value": 7216.562934365856, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1627.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 120.0, 118.0, 119.0, 127.0, 171.0, 124.0, 118.0, 119.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 135.0, 107.0, 182.0, 109.0, 149.0, 147.0, 158.0, 142.0, 119.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 230.0, 263.0, 199.0, 197.0, 204.0, 197.0, 197.0, 260.0, 216.0], "value": 216.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 72.0, 83.0, 103.0, 61.0, 60.0, 63.0, 52.0, 93.0, 82.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 71.0, 84.0, 74.0, 55.0, 90.0, 114.0, 88.0, 102.0, 113.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 132.0, 90.0, 94.0, 90.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 154.0, 152.0, 108.0, 161.0, 152.0, 99.0, 109.0, 118.0, 97.0], "value": 124.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 91.0, 109.0, 144.0, 119.0, 92.0, 118.0, 136.0, 125.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0, 60.0, 56.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 70.0, 78.0, 74.0, 69.0, 71.0, 72.0, 103.0, 105.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 92.0, 90.0, 92.0, 88.0, 89.0, 86.0, 99.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 163.0, 149.0, 155.0, 150.0, 153.0, 161.0, 190.0, 165.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 176.0, 174.0, 174.0, 178.0, 176.0, 175.0, 175.0, 171.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 70.0, 76.0, 91.0, 90.0, 96.0, 91.0, 92.0, 74.0], "value": 81.9, "unit": "score"}]}]} 09:02:16 INFO - Return code: 0 09:02:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:02:16 ERROR - # TBPL SUCCESS # 09:02:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:16 INFO - 2016-11-29 09:02:16.152 screenresolution[1662:10731] starting screenresolution argv=screenresolution get 09:02:16 INFO - 2016-11-29 09:02:16.155 screenresolution[1662:10731] Display 0: 1600x1200x32@60 09:02:16 INFO - 2016-11-29 09:02:16.161 screenresolution[1663:10734] starting screenresolution argv=screenresolution list 09:02:16 INFO - Available Modes on Display 0 09:02:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:16 INFO - Intel Iris: 09:02:16 INFO - Chipset Model: Intel Iris 09:02:16 INFO - Type: GPU 09:02:16 INFO - Bus: Built-In 09:02:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:16 INFO - Vendor: Intel (0x8086) 09:02:16 INFO - Device ID: 0x0a2e 09:02:16 INFO - Revision ID: 0x0009 09:02:16 INFO - Displays: 09:02:16 INFO - Display: 09:02:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:16 INFO - Display Serial Number: 1600x1200 60 09:02:16 INFO - Main Display: Yes 09:02:16 INFO - Mirror: Off 09:02:16 INFO - Online: Yes 09:02:16 INFO - Rotation: Supported 09:02:16 INFO - Return code: 0 09:02:16 INFO - Running post-action listener: _resource_record_post_action 09:02:16 INFO - [mozharness: 2016-11-29 17:02:16.401988Z] Finished run-tests step (success) 09:02:16 INFO - Running post-run listener: _resource_record_post_run 09:02:16 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38603264; Write bytes: 697190400; Read time: 243; Write time: 2008 09:02:16 INFO - TinderboxPrint: CPU usage
54.7% 09:02:16 INFO - TinderboxPrint: I/O read bytes / time
38,603,264 / 243 09:02:16 INFO - TinderboxPrint: I/O write bytes / time
697,190,400 / 2,008 09:02:16 INFO - TinderboxPrint: CPU idle
2,199.0 (45.3%) 09:02:16 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 09:02:16 INFO - TinderboxPrint: CPU user
1,670.0 (34.4%) 09:02:16 INFO - TinderboxPrint: Swap in / out
422,703,104 / 0 09:02:16 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 274432; Write bytes: 386344960; Read time: 6; Write time: 806 09:02:16 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35424768; Write bytes: 309506048; Read time: 218; Write time: 1196 09:02:16 INFO - Running post-run listener: _upload_blobber_files 09:02:16 INFO - Blob upload gear active. 09:02:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:02:16 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 09:02:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:02:17 INFO - (blobuploader) - INFO - Open directory for files ... 09:02:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:02:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:02:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:18 INFO - (blobuploader) - INFO - Done attempting. 09:02:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:02:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:02:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:19 INFO - (blobuploader) - INFO - Done attempting. 09:02:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:21 INFO - (blobuploader) - INFO - Done attempting. 09:02:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:21 INFO - Return code: 0 09:02:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f0d9e7829e35ed31da78b85c4a5a1852d187d3754fc77d22bd9347f6f11280390dcf95cab7d3343cc48342c96d734e2c6e4c813963d7df2b9a140126546756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c763d96725e9da214903ec1b145dcc8aa63a441aef624b0efe482706a0d143e3037f0e722ff7cf9651c32e17aeb77adba3fb32925e335fab57798821249fffc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22c2c10ce8bfd609767e3ad96941696cc14501bb442f4f693358805627e898a2a4c764042db0bd3cc6b6345b84cbab92cecc9ae4d87427bd422e36e3f7fed362"} 09:02:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:21 INFO - Contents: 09:02:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f0d9e7829e35ed31da78b85c4a5a1852d187d3754fc77d22bd9347f6f11280390dcf95cab7d3343cc48342c96d734e2c6e4c813963d7df2b9a140126546756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c763d96725e9da214903ec1b145dcc8aa63a441aef624b0efe482706a0d143e3037f0e722ff7cf9651c32e17aeb77adba3fb32925e335fab57798821249fffc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22c2c10ce8bfd609767e3ad96941696cc14501bb442f4f693358805627e898a2a4c764042db0bd3cc6b6345b84cbab92cecc9ae4d87427bd422e36e3f7fed362"} 09:02:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:21 INFO - Copying logs to upload dir... 09:02:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.307035 ========= master_lag: 1.65 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-29 09:02:23.617031) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-11-29 09:02:23.624164) ========= 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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f0d9e7829e35ed31da78b85c4a5a1852d187d3754fc77d22bd9347f6f11280390dcf95cab7d3343cc48342c96d734e2c6e4c813963d7df2b9a140126546756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c763d96725e9da214903ec1b145dcc8aa63a441aef624b0efe482706a0d143e3037f0e722ff7cf9651c32e17aeb77adba3fb32925e335fab57798821249fffc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22c2c10ce8bfd609767e3ad96941696cc14501bb442f4f693358805627e898a2a4c764042db0bd3cc6b6345b84cbab92cecc9ae4d87427bd422e36e3f7fed362"} build_url:https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010510 build_url: 'https://queue.taskcluster.net/v1/task/Az6ITlt_QS-x5mIsJWZbRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f0d9e7829e35ed31da78b85c4a5a1852d187d3754fc77d22bd9347f6f11280390dcf95cab7d3343cc48342c96d734e2c6e4c813963d7df2b9a140126546756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c763d96725e9da214903ec1b145dcc8aa63a441aef624b0efe482706a0d143e3037f0e722ff7cf9651c32e17aeb77adba3fb32925e335fab57798821249fffc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22c2c10ce8bfd609767e3ad96941696cc14501bb442f4f693358805627e898a2a4c764042db0bd3cc6b6345b84cbab92cecc9ae4d87427bd422e36e3f7fed362"}' ========= master_lag: 2.39 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-11-29 09:02:26.022088) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:02:26.022489) ========= 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.1LRxTqLjWU/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.N7xJp0OG1C/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.004529 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:02:26.049507) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 09:02:26.052870) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 09:02:26.053204) ========= ========= Total master_lag: 4.14 =========