builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0382 starttime: 1479880858.22 results: success (0) buildid: 20161122200337 builduid: d2826da50f784ee283523fc1fbd67c96 revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.222399) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.222816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.223082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.288212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.288508) ========= 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.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-22 22:00:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.15M=0.001s 2016-11-22 22:00:58 (8.15 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.491204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.803250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.803555) ========= 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.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.018840 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.852516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.852871) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --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 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-22 22:00:58,934 truncating revision to first 12 chars 2016-11-22 22:00:58,934 Setting DEBUG logging. 2016-11-22 22:00:58,934 attempt 1/10 2016-11-22 22:00:58,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ddfec7126ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 22:00:59,586 unpacking tar archive at: mozilla-central-0ddfec7126ec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910773 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:59.786420) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:59.786723) ========= script_repo_revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:59.787092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:59.787353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:59.806738) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 22:00:59.807093) ========= /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.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:00:59 INFO - MultiFileLogger online at 20161122 22:00:59 in /builds/slave/test 22:00:59 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 22:00:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:59 INFO - {'append_to_log': False, 22:00:59 INFO - 'base_work_dir': '/builds/slave/test', 22:00:59 INFO - 'blob_upload_branch': 'Firefox', 22:00:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:59 INFO - 'branch': 'Firefox', 22:01:00 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:00 INFO - 'config_files': ('talos/mac_config.py',), 22:01:00 INFO - 'default_actions': ('clobber', 22:01:00 INFO - 'read-buildbot-config', 22:01:00 INFO - 'download-and-extract', 22:01:00 INFO - 'populate-webroot', 22:01:00 INFO - 'create-virtualenv', 22:01:00 INFO - 'install', 22:01:00 INFO - 'run-tests'), 22:01:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:00 INFO - 'download_minidump_stackwalk': True, 22:01:00 INFO - 'download_symbols': 'ondemand', 22:01:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:00 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:00 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:00 INFO - 'installer_path': 'installer.exe', 22:01:00 INFO - 'log_level': 'info', 22:01:00 INFO - 'log_name': 'talos', 22:01:00 INFO - 'log_to_console': True, 22:01:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:01:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:01:00 INFO - 'opt_config_files': (), 22:01:00 INFO - 'pip_index': False, 22:01:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:01:00 INFO - 'cmd': ('bash', 22:01:00 INFO - '-c', 22:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:01:00 INFO - 'enabled': True, 22:01:00 INFO - 'halt_on_failure': False, 22:01:00 INFO - 'name': 'check_screen_resolution'},), 22:01:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:01:00 INFO - 'cmd': ('bash', 22:01:00 INFO - '-c', 22:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:01:00 INFO - 'enabled': True, 22:01:00 INFO - 'halt_on_failure': False, 22:01:00 INFO - 'name': 'check_screen_resolution'},), 22:01:00 INFO - 'run_cmd_checks_enabled': True, 22:01:00 INFO - 'sps_profile': False, 22:01:00 INFO - 'sps_profile_interval': 0, 22:01:00 INFO - 'suite': 'dromaeojs-e10s', 22:01:00 INFO - 'system_bits': '32', 22:01:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:00 INFO - 'title': 't-yosemite-r7-0382', 22:01:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:00 INFO - 'use_talos_json': True, 22:01:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:01:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:00 INFO - 'work_dir': 'build'} 22:01:00 INFO - [mozharness: 2016-11-23 06:01:00.005608Z] Running clobber step. 22:01:00 INFO - Running pre-action listener: _resource_record_pre_action 22:01:00 INFO - Running main action method: clobber 22:01:00 INFO - rmtree: /builds/slave/test/build 22:01:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:01 INFO - Running post-action listener: _resource_record_post_action 22:01:01 INFO - [mozharness: 2016-11-23 06:01:01.641702Z] Finished clobber step (success) 22:01:01 INFO - [mozharness: 2016-11-23 06:01:01.641860Z] Running read-buildbot-config step. 22:01:01 INFO - Running pre-action listener: _resource_record_pre_action 22:01:01 INFO - Running main action method: read_buildbot_config 22:01:01 INFO - Using buildbot properties: 22:01:01 INFO - { 22:01:01 INFO - "project": "", 22:01:01 INFO - "product": "firefox", 22:01:01 INFO - "script_repo_revision": "production", 22:01:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:01:01 INFO - "repository": "", 22:01:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:01:01 INFO - "buildid": "20161122200337", 22:01:01 INFO - "pgo_build": "False", 22:01:01 INFO - "basedir": "/builds/slave/test", 22:01:01 INFO - "buildnumber": 0, 22:01:01 INFO - "slavename": "t-yosemite-r7-0382", 22:01:01 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:01:01 INFO - "platform": "yosemite_r7", 22:01:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:01:01 INFO - "branch": "mozilla-central", 22:01:01 INFO - "repo_path": "mozilla-central", 22:01:01 INFO - "revision": "0ddfec7126ec503b54df9c4b7c3b988906f6c882", 22:01:01 INFO - "stage_platform": "macosx64", 22:01:01 INFO - "builduid": "d2826da50f784ee283523fc1fbd67c96", 22:01:01 INFO - "slavebuilddir": "test" 22:01:01 INFO - } 22:01:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:01:01 INFO - Running post-action listener: _resource_record_post_action 22:01:01 INFO - [mozharness: 2016-11-23 06:01:01.645246Z] Finished read-buildbot-config step (success) 22:01:01 INFO - [mozharness: 2016-11-23 06:01:01.645402Z] Running download-and-extract step. 22:01:01 INFO - Running pre-action listener: _resource_record_pre_action 22:01:01 INFO - Running main action method: download_and_extract 22:01:01 INFO - mkdir: /builds/slave/test/build/tests 22:01: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')]} 22:01:01 INFO - trying https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:01:01 INFO - Downloading https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/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 22:01:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/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 22:01:03 INFO - Downloaded 1199 bytes. 22:01:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:01:03 INFO - Using the following test package requirements: 22:01:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:01:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:01:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:01:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'jsshell-mac.zip'], 22:01:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:01:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:01:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:01:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:01:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:01:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:01:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:01:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:01:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:01:03 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:01:03 INFO - Http code: 200 22:01:03 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 22:01:03 INFO - x-amz-cf-id: foLEsFO48_lRX6hUztWiBbjahdB4EBV1ATDnjz6XSEMTG1XevTtB5Q== 22:01:03 INFO - x-amz-version-id: lZJOTkZxWTXC5y7DfHxxmg_sNgt7SsjX 22:01:04 INFO - Expected file size: 16267007 22:01:04 INFO - Obtained file size: 16267007 22:01:10 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 22:01:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:01:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:01:10 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:01:11 INFO - Http code: 200 22:01:11 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 22:01:11 INFO - x-amz-cf-id: xhVfRqr-EU6L8Hxr5tEvq-p--fT_jmMvKrOsL-jCQi0--6hXLvHqbQ== 22:01:11 INFO - x-amz-version-id: lZJOTkZxWTXC5y7DfHxxmg_sNgt7SsjX 22:01:11 INFO - Expected file size: 16267007 22:01:11 INFO - Obtained file size: 16267007 22:01:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:01:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:01:17 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:01:18 INFO - Http code: 200 22:01:18 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 22:01:18 INFO - x-amz-cf-id: WTyjLM6aQkySkImaknF9kjGpbTQNbUYslhSVmv-xB0IG7nVCr4b84A== 22:01:18 INFO - x-amz-version-id: ZuqwNr6sw3s4oTaiXPzYJc4owAnbKEZr 22:01:19 INFO - Expected file size: 14409506 22:01:19 INFO - Obtained file size: 14409506 22:01:19 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')]} 22:01:19 INFO - trying https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:01:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/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 22:01:22 INFO - Downloaded 107522115 bytes. 22:01:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:22 INFO - mkdir: /builds/slave/test/properties 22:01:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:22 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:22 INFO - Contents: 22:01:22 INFO - build_url:https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:23 INFO - Running post-action listener: _resource_record_post_action 22:01:23 INFO - Running post-action listener: set_extra_try_arguments 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.508249Z] Finished download-and-extract step (success) 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.508360Z] Running populate-webroot step. 22:01:23 INFO - Running pre-action listener: _resource_record_pre_action 22:01:23 INFO - Running main action method: populate_webroot 22:01:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:23 INFO - '--tppagecycles', 22:01:23 INFO - '7'], 22:01:23 INFO - 'tests': ['tsvgm']}}, 22:01:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['damp', 'tps']}, 22:01:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'tests': ['damp', 'tps']}, 22:01:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['dromaeo_dom']}, 22:01:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['basic_compositor_video']}, 22:01:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:01:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tsvgx', 22:01:23 INFO - 'tsvgr_opacity', 22:01:23 INFO - 'tart', 22:01:23 INFO - 'tscrollx', 22:01:23 INFO - 'cart']}, 22:01:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:23 INFO - 'tsvgr_opacity', 22:01:23 INFO - 'tart', 22:01:23 INFO - 'tscrollx', 22:01:23 INFO - 'cart']}, 22:01:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tp5o']}, 22:01:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:01:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s', 22:01:23 INFO - '--xperf_path', 22:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:23 INFO - 'tests': ['tp5n']}, 22:01:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--xperf_path', 22:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:23 INFO - 'tests': ['tp5n']}}, 22:01:23 INFO - 'talos.zip': {'path': '', 22:01:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:23 INFO - Running post-action listener: _resource_record_post_action 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.526394Z] Finished populate-webroot step (success) 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.526528Z] Running create-virtualenv step. 22:01:23 INFO - Running pre-action listener: _resource_record_pre_action 22:01:23 INFO - Running main action method: create_virtualenv 22:01:23 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:23 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 22:01:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:23 INFO - Using real prefix '/tools/python27' 22:01:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:24 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:27 INFO - Installing pip.................done. 22:01:27 INFO - Return code: 0 22:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:01:27 INFO - Reading from file tmpfile_stdout 22:01:27 INFO - Output received: 22:01:27 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) 22:01:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:27 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 22:01:27 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 22:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:27 INFO - 'HOME': '/Users/cltbld', 22:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PAGER': '/bin/cat', 22:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:27 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - 'XPC_FLAGS': '0x0', 22:01:27 INFO - 'XPC_SERVICE_NAME': '0', 22:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:27 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 22:01:27 INFO - Cleaning up... 22:01:27 INFO - Return code: 0 22:01:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:27 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 22:01:27 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 22:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:27 INFO - 'HOME': '/Users/cltbld', 22:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PAGER': '/bin/cat', 22:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:27 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - 'XPC_FLAGS': '0x0', 22:01:27 INFO - 'XPC_SERVICE_NAME': '0', 22:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:27 INFO - Downloading/unpacking psutil>=3.1.1 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 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 22:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:31 INFO - Installing collected packages: psutil 22:01:31 INFO - Running setup.py install for psutil 22:01:31 INFO - building 'psutil._psutil_osx' extension 22:01:31 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 INFO - building 'psutil._psutil_posix' extension 22:01:32 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 22:01:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:01:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:01:32 INFO - ^ 22:01:32 INFO - 1 warning generated. 22:01:32 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 22:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:32 INFO - Successfully installed psutil 22:01:32 INFO - Cleaning up... 22:01:32 INFO - Return code: 0 22:01:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:32 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')]} 22:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:32 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')]} 22:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:32 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:32 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 22:01:32 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 22:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:32 INFO - 'HOME': '/Users/cltbld', 22:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:32 INFO - 'LOGNAME': 'cltbld', 22:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:32 INFO - 'MOZ_NO_REMOTE': '1', 22:01:32 INFO - 'NO_EM_RESTART': '1', 22:01:32 INFO - 'PAGER': '/bin/cat', 22:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:32 INFO - 'PWD': '/builds/slave/test', 22:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:32 INFO - 'SHELL': '/bin/bash', 22:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:32 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:32 INFO - 'USER': 'cltbld', 22:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:32 INFO - 'XPC_FLAGS': '0x0', 22:01:32 INFO - 'XPC_SERVICE_NAME': '0', 22:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:35 INFO - Installing collected packages: mozsystemmonitor 22:01:35 INFO - Running setup.py install for mozsystemmonitor 22:01:35 INFO - Successfully installed mozsystemmonitor 22:01:35 INFO - Cleaning up... 22:01:35 INFO - Return code: 0 22:01:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:35 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:35 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 22:01:35 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 22:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:35 INFO - 'HOME': '/Users/cltbld', 22:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:35 INFO - 'LOGNAME': 'cltbld', 22:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:35 INFO - 'MOZ_NO_REMOTE': '1', 22:01:35 INFO - 'NO_EM_RESTART': '1', 22:01:35 INFO - 'PAGER': '/bin/cat', 22:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:35 INFO - 'PWD': '/builds/slave/test', 22:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:35 INFO - 'SHELL': '/bin/bash', 22:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:35 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:35 INFO - 'USER': 'cltbld', 22:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:35 INFO - 'XPC_FLAGS': '0x0', 22:01:35 INFO - 'XPC_SERVICE_NAME': '0', 22:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:36 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:38 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:39 INFO - warning: no files found matching '*.txt' 22:01:39 INFO - no previously-included directories found matching 'build' 22:01:39 INFO - no previously-included directories found matching 'dist' 22:01:39 INFO - no previously-included directories found matching '.git*' 22:01:39 INFO - Installing collected packages: jsonschema, functools32 22:01:39 INFO - Running setup.py install for functools32 22:01:39 INFO - warning: no files found matching '*.txt' 22:01:39 INFO - no previously-included directories found matching 'build' 22:01:39 INFO - no previously-included directories found matching 'dist' 22:01:39 INFO - no previously-included directories found matching '.git*' 22:01:39 INFO - Successfully installed jsonschema functools32 22:01:39 INFO - Cleaning up... 22:01:39 INFO - Return code: 0 22:01:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:01: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 functools32==3.2.3-2 22:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:39 INFO - 'HOME': '/Users/cltbld', 22:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:39 INFO - 'LOGNAME': 'cltbld', 22:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:39 INFO - 'MOZ_NO_REMOTE': '1', 22:01:39 INFO - 'NO_EM_RESTART': '1', 22:01:39 INFO - 'PAGER': '/bin/cat', 22:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:39 INFO - 'PWD': '/builds/slave/test', 22:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:39 INFO - 'SHELL': '/bin/bash', 22:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:39 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:39 INFO - 'USER': 'cltbld', 22:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:39 INFO - 'XPC_FLAGS': '0x0', 22:01:39 INFO - 'XPC_SERVICE_NAME': '0', 22:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:01:39 INFO - Cleaning up... 22:01:39 INFO - Return code: 0 22:01:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:01: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 blobuploader==1.2.4 22:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:39 INFO - 'HOME': '/Users/cltbld', 22:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:39 INFO - 'LOGNAME': 'cltbld', 22:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:39 INFO - 'MOZ_NO_REMOTE': '1', 22:01:39 INFO - 'NO_EM_RESTART': '1', 22:01:39 INFO - 'PAGER': '/bin/cat', 22:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:39 INFO - 'PWD': '/builds/slave/test', 22:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:39 INFO - 'SHELL': '/bin/bash', 22:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:39 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:39 INFO - 'USER': 'cltbld', 22:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:39 INFO - 'XPC_FLAGS': '0x0', 22:01:39 INFO - 'XPC_SERVICE_NAME': '0', 22:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:39 INFO - Downloading/unpacking blobuploader==1.2.4 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:42 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 INFO - Downloading docopt-0.6.1.tar.gz 22:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:43 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:43 INFO - Running setup.py install for blobuploader 22:01:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:43 INFO - Running setup.py install for requests 22:01:44 INFO - Running setup.py install for docopt 22:01:44 INFO - Successfully installed blobuploader requests docopt 22:01:44 INFO - Cleaning up... 22:01:44 INFO - Return code: 0 22:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:44 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:44 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 22:01:44 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 22:01:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:44 INFO - 'HOME': '/Users/cltbld', 22:01:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:44 INFO - 'LOGNAME': 'cltbld', 22:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:44 INFO - 'MOZ_NO_REMOTE': '1', 22:01:44 INFO - 'NO_EM_RESTART': '1', 22:01:44 INFO - 'PAGER': '/bin/cat', 22:01:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:44 INFO - 'PWD': '/builds/slave/test', 22:01:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:44 INFO - 'SHELL': '/bin/bash', 22:01:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:44 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:44 INFO - 'USER': 'cltbld', 22:01:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:44 INFO - 'XPC_FLAGS': '0x0', 22:01:44 INFO - 'XPC_SERVICE_NAME': '0', 22:01:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-TehM7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gJ4TH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Z05u_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-OthRwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-aDQMQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4CbVwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8pequn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gHLNt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ZmxKTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6odG8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-nBHx5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-t8HvdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4Gi_H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:46 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Rmm4Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:46 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-CgEI5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:46 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-L32ZZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:46 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-vYS_X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:46 INFO - Running setup.py install for manifestparser 22:01:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:46 INFO - Running setup.py install for mozcrash 22:01:46 INFO - Running setup.py install for mozdebug 22:01:46 INFO - Running setup.py install for mozdevice 22:01:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozfile 22:01:47 INFO - Running setup.py install for mozhttpd 22:01:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozinfo 22:01:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozInstall 22:01:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozleak 22:01:47 INFO - Running setup.py install for mozlog 22:01:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for moznetwork 22:01:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozprocess 22:01:48 INFO - Running setup.py install for mozprofile 22:01:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozrunner 22:01:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozscreenshot 22:01:48 INFO - Running setup.py install for moztest 22:01:48 INFO - Running setup.py install for mozversion 22:01:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:49 INFO - Cleaning up... 22:01:49 INFO - Return code: 0 22:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:49 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:49 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 22:01:49 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 22:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:49 INFO - 'HOME': '/Users/cltbld', 22:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:49 INFO - 'LOGNAME': 'cltbld', 22:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:49 INFO - 'MOZ_NO_REMOTE': '1', 22:01:49 INFO - 'NO_EM_RESTART': '1', 22:01:49 INFO - 'PAGER': '/bin/cat', 22:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:49 INFO - 'PWD': '/builds/slave/test', 22:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:49 INFO - 'SHELL': '/bin/bash', 22:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:49 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:49 INFO - 'USER': 'cltbld', 22:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:49 INFO - 'XPC_FLAGS': '0x0', 22:01:49 INFO - 'XPC_SERVICE_NAME': '0', 22:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ckaKJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:49 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)) 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-L0b02b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:49 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)) 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-UwN8Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:49 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)) 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0s_CkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:49 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)) 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-z5GDtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:49 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)) 22:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-mQPQRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-wtrym5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-TEykUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-n783nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LbSN7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-H4620e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-riaPvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-siIFjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-oaK7lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Qb3mtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-btSgFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:51 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-C17SC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:53 INFO - Downloading blessings-1.6.tar.gz 22:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:53 INFO - Installing collected packages: blessings 22:01:53 INFO - Running setup.py install for blessings 22:01:54 INFO - Successfully installed blessings 22:01:54 INFO - Cleaning up... 22:01:54 INFO - Return code: 0 22:01:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:54 INFO - Reading from file tmpfile_stdout 22:01:54 INFO - Current package versions: 22:01:54 INFO - blessings == 1.6 22:01:54 INFO - blobuploader == 1.2.4 22:01:54 INFO - docopt == 0.6.1 22:01:54 INFO - functools32 == 3.2.3-2 22:01:54 INFO - jsonschema == 2.5.1 22:01:54 INFO - manifestparser == 1.1 22:01:54 INFO - mozInstall == 1.12 22:01:54 INFO - mozcrash == 1.0 22:01:54 INFO - mozdebug == 0.1 22:01:54 INFO - mozdevice == 0.49 22:01:54 INFO - mozfile == 1.2 22:01:54 INFO - mozhttpd == 0.7 22:01:54 INFO - mozinfo == 0.9 22:01:54 INFO - mozleak == 0.1 22:01:54 INFO - mozlog == 3.4 22:01:54 INFO - moznetwork == 0.27 22:01:54 INFO - mozprocess == 0.23 22:01:54 INFO - mozprofile == 0.28 22:01:54 INFO - mozrunner == 6.13 22:01:54 INFO - mozscreenshot == 0.1 22:01:54 INFO - mozsystemmonitor == 0.3 22:01:54 INFO - moztest == 0.7 22:01:54 INFO - mozversion == 1.4 22:01:54 INFO - psutil == 3.1.1 22:01:54 INFO - requests == 1.2.3 22:01:54 INFO - wsgiref == 0.1.2 22:01:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 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 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:54 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 22:01:54 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 22:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:54 INFO - 'HOME': '/Users/cltbld', 22:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:54 INFO - 'LOGNAME': 'cltbld', 22:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:54 INFO - 'MOZ_NO_REMOTE': '1', 22:01:54 INFO - 'NO_EM_RESTART': '1', 22:01:54 INFO - 'PAGER': '/bin/cat', 22:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:54 INFO - 'PWD': '/builds/slave/test', 22:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:54 INFO - 'SHELL': '/bin/bash', 22:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:54 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:54 INFO - 'USER': 'cltbld', 22:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:54 INFO - 'XPC_FLAGS': '0x0', 22:01:54 INFO - 'XPC_SERVICE_NAME': '0', 22:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 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)) 22:01:54 INFO - Cleaning up... 22:01:54 INFO - Return code: 0 22:01:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b70c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88a2cb1a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, '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.xtSjwm98zU/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.NIZAvW29Zb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 22:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:54 INFO - 'HOME': '/Users/cltbld', 22:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:54 INFO - 'LOGNAME': 'cltbld', 22:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:54 INFO - 'MOZ_NO_REMOTE': '1', 22:01:54 INFO - 'NO_EM_RESTART': '1', 22:01:54 INFO - 'PAGER': '/bin/cat', 22:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:54 INFO - 'PWD': '/builds/slave/test', 22:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:54 INFO - 'SHELL': '/bin/bash', 22:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:01:54 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:54 INFO - 'USER': 'cltbld', 22:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:54 INFO - 'XPC_FLAGS': '0x0', 22:01:54 INFO - 'XPC_SERVICE_NAME': '0', 22:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:54 INFO - Cleaning up... 22:01:54 INFO - Return code: 0 22:01:54 INFO - Running post-action listener: _resource_record_post_action 22:01:54 INFO - Running post-action listener: _start_resource_monitoring 22:01:54 INFO - Starting resource monitoring. 22:01:54 INFO - [mozharness: 2016-11-23 06:01:54.964096Z] Finished create-virtualenv step (success) 22:01:54 INFO - [mozharness: 2016-11-23 06:01:54.965081Z] Running install step. 22:01:54 INFO - Running pre-action listener: _resource_record_pre_action 22:01:54 INFO - Running main action method: install 22:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:55 INFO - Reading from file tmpfile_stdout 22:01:55 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:55 INFO - Reading from file tmpfile_stdout 22:01:55 INFO - Output received: 22:01:55 INFO - Usage: mozinstall [options] installer 22:01:55 INFO - Options: 22:01:55 INFO - -h, --help show this help message and exit 22:01:55 INFO - -d DEST, --destination=DEST 22:01:55 INFO - Directory to install application into. [default: 22:01:55 INFO - "/builds/slave/test"] 22:01:55 INFO - --app=APP Application being installed. [default: firefox] 22:01:55 INFO - mkdir: /builds/slave/test/build/application 22:01:55 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'] 22:01:55 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 22:02:27 INFO - Reading from file tmpfile_stdout 22:02:27 INFO - Output received: 22:02:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:02:27 INFO - Running post-action listener: _resource_record_post_action 22:02:27 INFO - [mozharness: 2016-11-23 06:02:27.483938Z] Finished install step (success) 22:02:27 INFO - [mozharness: 2016-11-23 06:02:27.484040Z] Running run-tests step. 22:02:27 INFO - Running pre-action listener: _resource_record_pre_action 22:02:27 INFO - Running main action method: run_tests 22:02:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:02:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:02:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:02:27 INFO - 2016-11-22 22:02:27.496 screenresolution[1930:15736] starting screenresolution argv=screenresolution get 22:02:27 INFO - 2016-11-22 22:02:27.499 screenresolution[1930:15736] Display 0: 1600x1200x32@60 22:02:27 INFO - 2016-11-22 22:02:27.504 screenresolution[1931:15738] starting screenresolution argv=screenresolution list 22:02:27 INFO - Available Modes on Display 0 22:02:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:02:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:02:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:02:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:02:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:02:27 INFO - Intel Iris: 22:02:27 INFO - Chipset Model: Intel Iris 22:02:27 INFO - Type: GPU 22:02:27 INFO - Bus: Built-In 22:02:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:02:27 INFO - Vendor: Intel (0x8086) 22:02:27 INFO - Device ID: 0x0a2e 22:02:27 INFO - Revision ID: 0x0009 22:02:27 INFO - Displays: 22:02:27 INFO - Display: 22:02:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:02:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:02:27 INFO - Display Serial Number: 1600x1200 60 22:02:27 INFO - Main Display: Yes 22:02:27 INFO - Mirror: Off 22:02:27 INFO - Online: Yes 22:02:27 INFO - Rotation: Supported 22:02:27 INFO - Return code: 0 22:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:02:27 INFO - Python 2.7.3 22:02:27 INFO - Return code: 0 22:02:27 INFO - grabbing minidump binary from tooltool 22:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:27 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 0x10b5acd30>, '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 0x10b413db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:02:27 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 22:02:27 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 22:02:27 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 22:02:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:02:27 INFO - Return code: 0 22:02:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:02:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:02:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:02:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:02:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:02:27 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/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 22:02:27 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/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 22:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xtSjwm98zU/Render', 22:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:02:27 INFO - 'HOME': '/Users/cltbld', 22:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:02:27 INFO - 'LOGNAME': 'cltbld', 22:02:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:02:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:27 INFO - 'MOZ_NO_REMOTE': '1', 22:02:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:02:27 INFO - 'NO_EM_RESTART': '1', 22:02:27 INFO - 'PAGER': '/bin/cat', 22:02:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:27 INFO - 'PWD': '/builds/slave/test', 22:02:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:02:27 INFO - 'SHELL': '/bin/bash', 22:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NIZAvW29Zb/Listeners', 22:02:27 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 22:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:02:27 INFO - 'USER': 'cltbld', 22:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:27 INFO - 'XPC_FLAGS': '0x0', 22:02:27 INFO - 'XPC_SERVICE_NAME': '0', 22:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:02:27 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/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 22:02:28 INFO - mozversion application_buildid: 20161122200337 22:02:28 INFO - mozversion application_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 22:02:28 INFO - mozversion application_display_name: Nightly 22:02:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:02:28 INFO - mozversion application_name: Firefox 22:02:28 INFO - mozversion application_remotingname: firefox 22:02:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:02:28 INFO - mozversion application_vendor: Mozilla 22:02:28 INFO - mozversion application_version: 53.0a1 22:02:28 INFO - mozversion platform_buildid: 20161122200337 22:02:28 INFO - mozversion platform_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 22:02:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:02:28 INFO - mozversion platform_version: 53.0a1 22:02:28 INFO - using testdate: 1479880948 22:02:28 INFO - actual date: 1479880948 22:02:28 INFO - starting webserver on 'localhost:51686' 22:02:28 INFO - SUITE-START | Running 2 tests 22:02:28 INFO - TEST-START | dromaeo_css 22:02:28 INFO - operating with platform_type : mac_ 22:02:28 INFO - Initialising browser for dromaeo_css test... 22:02:28 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpNx6Fd1/profile http://localhost:51686/getInfo.html) 22:02:31 INFO - PROCESS | 1941 | 2016-11-22 22:02:31.065 plugin-container[1943:15902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484f, name = 'com.apple.tsm.portname' 22:02:31 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:02:31 INFO - PROCESS | 1941 | 2016-11-22 22:02:31.068 plugin-container[1943:15902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 22:02:31 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:02:31 INFO - PROCESS | 1941 | 2016-11-22 22:02:31.068 plugin-container[1943:15902] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:02:31 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 22:02:31 INFO - PROCESS | 1941 | colorDepth:24 22:02:31 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 22:02:31 INFO - PROCESS | 1941 | __metrics 22:02:31 ERROR - PROCESS | 1941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 22:02:32 INFO - TEST-INFO | 1941: exit 0 22:02:32 INFO - Browser initialized. 22:02:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:02:32 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpNx6Fd1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:34 INFO - PROCESS | 1944 | 2016-11-22 22:02:34.919 plugin-container[1945:16022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 22:02:34 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:02:34 INFO - PROCESS | 1944 | 2016-11-22 22:02:34.919 plugin-container[1945:16022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 22:02:34 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:02:34 INFO - PROCESS | 1944 | 2016-11-22 22:02:34.919 plugin-container[1945:16022] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:02:44 INFO - PROCESS | 1944 | RSS: Main: 241680384 22:02:44 INFO - PROCESS | 1944 | 22:03:34 INFO - PROCESS | 1944 | 1479881014168 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] 22:05:49 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51686/tests/dromaeo/cssquery-ext.html) 22:05:50 INFO - PROCESS | 1944 | RSS: Main: 248573952 22:05:50 INFO - PROCESS | 1944 | 22:08:53 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-ext.html (next: http://localhost:51686/tests/dromaeo/cssquery-jquery.html) 22:08:53 INFO - PROCESS | 1944 | RSS: Main: 260034560 22:08:53 INFO - PROCESS | 1944 | 22:11:55 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51686/tests/dromaeo/cssquery-mootools.html) 22:11:56 INFO - PROCESS | 1944 | RSS: Main: 264425472 22:11:56 INFO - PROCESS | 1944 | 22:14:58 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51686/tests/dromaeo/cssquery-prototype.html) 22:14:58 INFO - PROCESS | 1944 | RSS: Main: 265252864 22:14:58 INFO - PROCESS | 1944 | 22:18:01 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51686/tests/dromaeo/cssquery-yui.html) 22:18:02 INFO - PROCESS | 1944 | RSS: Main: 269619200 22:18:02 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-yui.html (next: http://localhost:51686/tests/dromaeo/cssquery-dojo.html) 22:21:05 INFO - PROCESS | 1944 | RSS: Main: 272531456 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | __start_tp_report 22:21:05 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 22:21:05 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 22:21:05 INFO - PROCESS | 1944 | |i|pagename|runs| 22:21:05 INFO - PROCESS | 1944 | |0;dojo.html;3255.6744325567433;3292.3744488547172;3334.7661365294844;3340.2317466982595;3354.798712077274;4454.933176002362;5370.355557333126;5436.75534600943;5460;5504.2018907258425;4465.553444655533;5464.754086066127;5549.667019978794;5561.694106824123;5597.048501754702;4977.651564390494;5020.146575082226;5054.115529782273;5055.115354812911;5192.558632516222;4514.819407223724;4876.439209490918;5059.898802023958;5149.40781810091;5223.869403264911;4108.383742438658;5112.97443512782;5152.84541463753;5189.42916279209;5190.195519694454;5004.299398084271;5047.823326183566;5048.318477005594;5075.111855425286;5078.923816142761;4811.9518804812205;4993.176125939216;5009.298698182258;5024.572911302544;5032.04391165681;4454.910901781946;5196.246544251062;5457.263269458612;5491.313585801775;5498.3127109111365;5986.431289027535;5988.101784732282;5999.100134979744;6031.758729650809;6064.484518815906;6031.306399764039;6039.667818269993;6061.696915154269;6066.332703402622;6067.393260673942;5472.315960504937;5513.365962914316;5531.751071201806;5564.33228012637;5586;6111.98679349419;6128.80869161996;6135.877282454326;6160.507159427235;6173.722182501798;4957.7273249971595;5855.765769369177;5896.469317761422;5906.793262235801;5916.704164791743;6002.789902353396;6015.789447369321;6025.3974602540275;6040.305364883006;6048.425399587032;5771.5571107223195;5825.941740582624;5828.388019257917;5840.153177789197;5864.560157988168;5879.5002424794375;5950.375210602911;5970.223870896755;5982.2223110996165;6027.156198132265;4060.3097473428998;4729.864832440189;4746.501617330199;4776.5701086902345;4784.40194975628;4134.276501612206;4226.429432026698;4247.426597409372;4248.575142485726;4299.419578356944;5182.170852663556;5407.270018547524;5502.257195278667;5691.8292451226525;5804.88390232193;6020.879582408327;6055.333913269536;6064.605800622945;6066.272047354258;6106.542009349317;5713.771449141987;5744.569157313218;5762.078067509262;5776.306843178845;5783.71081445926;5077.213031980049;5129.743512824344;5186.299849520342;5189.506996835295;5191.766370513336;6131.132004888217;6136.427173609184;6174.289956654862;6192.287886892975;6211.503079753699;6001.4598686116715;6005.099235114768;6008.669523176267;6030.969845150747;6059.6364218147055;6108.419700128481;6179.104029915727;6190.102435146789;6226.097215903578;6227.782027629011;5783.624064435799;5825.446582574648;5837.562182836389;5883.264591926009;5895.852603684943;4792.329073929723;4803;5941.643501389931;5957;6014.278286605635;5710.2576665033275;5795.536357091507;5797.797077102281;5805.129230615442;5807.419258074159;5751.654900705971;5773.307203135482;5783.7975670853;5835.9708201458725;5928.110783382527;5509.256250406304;5723.456271654352;5726.713664316851;5727.055035919193;5753.280839895013;5262.89479209355;5516.834494965157;5703.144528320785;5735.684537350486;5803.984302747087;5539.916901246565;5635.211070450058;5659;5659.915101273402;5680.7443665036 22:21:05 INFO - PROCESS | 1944 | |1;ext.html;468.30924386529875;516.7932826869252;520.4977197004889;520.7240162713761;526.0426024635165;37427;38873.41689874651;38928.416073758846;39010.609893901084;40754.79622601883;32471.83764081182;39689.404658930136;39966.40050399247;40086.997825054386;40185.59814401855;8493.532855692934;9161.679341223058;9368.34421590489;10804.405757683324;11192.720181995475;11114.444277786119;11159.776804463905;11173.776524469506;11192.216544841864;11201.60794372197;11177.888221117766;11216.270942388719;11222.607208747695;11238;11296.435178241096;11149;11184.664460066211;11193.66419007431;11194.10447164225;11240.325580465116;9372.765680858032;9595.944446110921;10563.63027294041;11021.614243501439;11090.55637774488;38160.61839381598;38456.230875382615;38623.8068809657;38640.80679596612;38648.61351386478;3284.671532846696;3497.405441075023;3682.576503702082;3908.5700572936953;3969.28552860485;28620.713792862014;29555.408891822044;29631.814727410878;29671.70328296711;29969.70030299691;3230.4508233600345;3239.611246650417;3243.708066274047;3331;3352.228987332927;462327;467395.98906016775;469478;473083;478866;46649.7667511667;55317.723411382685;55332.44667553273;55440.44559554433;55484;61736.38263617396;62046;62194;62356.06465903015;62486.68756656188;15504.922475387551;15896.443624473088;15920.044797312199;16034.839651603568;16067.839321606869;11115.72210694739;11291.322520648788;12065.638030858912;12179.756404871852;12267.877321226852;18072.728909066376;18247.817521824876;18323.35868244605;18340.81659183391;18341;6555.578823505964;7780.221977802287;7796.961015194887;7826.5520878637635;7890.802729931683;839.5088873009236;840.5671079394117;918.9908100918905;945.7304668169538;972.7324985628867;26228.73771262301;28902;29385;29393.971211007512;29801.552976705367;911.9726408207632;1035.5081336365317;1058.216919479595;1146.9598564050218;1162.1574358589955;11574.247673901169;12516;13875.514356997659;15060.021098628773;15361.924665273547;666573;668291;668444;670506;674211;54348;54917.1762423564;54995;55013.62465938384;55152.7604877536;1302.4790083966489;1352.3373546961918;1365.1945352242224;1370.7258548290183;1377.4696741754296;27889.721102789525;28740.42519149647;29013.274668133465;29311.827526898684;29378.265543361587;47722.28416573685;48074.759626201645;48218.5178148214;48314.51685483241;48365;514155;574950;576845;577930;579204;22769.658455122855;23408.648870266617;24545.754542454346;25465.236042918743;25640.871795640902;42994;43361;43578.56421435745;43723;44181.77909110434;30999;32430;32496.35007299888;36794;37081.443778343884;36260;36420.63579364278;37049.259014819014;37375.06562335963;37564.62435375721;35706.75026374064;36040.63959360373;36182;36644.45033324556;36980.81509592435;31636.683633163375;32934.670653293164;32941.67058329386;33016.50475242825;33043.33913321672;22575.66136507921;24431.26706198817;24538.631920521562;24972;25027.87486062558 22:21:05 INFO - PROCESS | 1944 | |2;jquery.html;342.3597871979399;344.49015457123454;373.9551253849539;380.51484357444264;387.1559999201739;16075.437359692412;18085.367012154577;18368.17343219555;19768.505787355305;19906.502337441572;16776.41282555111;19673.803261967376;19844.206231750748;19850.90820004899;20182.89908550459;5409.891802163964;5776.39347868474;5784.45047720468;5784.855378615526;5787.276590426197;5510.614257002011;5643.971780141093;5814.738336774855;5897;5923.526117910556;5796.3334216565;5889.469947704706;5901.704914754267;5903.645781253117;5925.970370148143;4930.3344048553345;5672;5870.501007414375;5884.352721200665;5905.29136503618;4179.247735407625;5639.605227634067;5654.74553645087;5692.658440493542;5708.315002199763;12886.420111094929;12937.35313234344;12945.741085178244;13141;13263.204207747469;2026.63520566298;2331;2451.5464639041834;2604.817662763607;2739.1371717908796;9452.385594936308;10050.396976181453;10380.325278856926;11752.177347585672;15394.230288485644;1818.1818181818235;1841.254292011724;1875.22178296007;1890.6974884018628;1898.4020033689344;114864;123254;124117;125534.74465255321;125803;11265.662030139109;11545.653630391102;11557.364344961108;11611.419429028514;11721.589744358907;12177.878221217674;14458;14458;14521.927390363191;14525.92737036308;7492.288232617892;8296.751097467086;8394.202550757669;8476;8505.23452889243;8146.75559733209;8162.428629995843;8181.509109453452;8348.415610907177;8370.916290837136;4386.583274588909;4579.519150489217;4582.885427864263;4605.319058543674;4622.46841613212;1907.4468404162915;1956.7260583518319;1959.9902000489906;1964.7838737738837;1965.5282732144183;824.5506199121493;884.4648987362682;887.9023307436177;914.3553794574809;1025.3335332034237;15423.691526169414;18099.91400515973;19488.317908873403;20284.695729564068;22517.662235066487;810.5501446697021;909.2226146644524;925.3430064654036;926.9304802139867;940.9952950235205;9424.858627120599;10222.568840362355;10300.330478518872;10653.467326633638;10769.515371808131;492407;495088;498115.50942245056;571547;576470;107328;110789;113372.43313783378;132006;142060;921.447131720962;977.423320241047;993.1756641987133;993.7627653571205;996.2080146283722;18931.526711831764;19261.422157335302;19272;19281.710774338677;19381.612367752285;56084.43915560792;56706.716466417405;57824.71087644535;58243.4175658238;58687;83740.16259837324;84603.5769821147;85434.14565854262;86188.13811861801;86836;8026.43814932949;8029.638666260149;8217.30152937007;8225.547594882339;8292.87560686578;12669;12877.8068328977;12894.806577901527;13107.475700972236;13136.27750473733;10988.066014388867;11298.717532061764;11381.829272560753;11517.712057198638;11535;10734.355938643706;11072.11423086135;11106.61126860556;11407.543698251977;11463.656090317303;11344.602938897098;11477.598284060017;11647.825282620937;11775.882241177478;11814.763704726029;6833.45332373399;7328.560286382834;7342.926570734225;7591.772246832604;8464.365172612203;9890.307678462439;10463.05832475081;11214.15893807984;11366.431678416211;11596.826047609462 22:21:05 INFO - PROCESS | 1944 | |3;mootools.html;1415.6390120519268;1461.846506116858;1634.3707672546075;1775.840174384305;1816.2008716164878;7459.179490256068;7646.235376462351;8033.196680331964;8429.241368276855;8439.198276163763;7901;8120.147384524614;8123.715669951553;8309.169083091689;8316.833663326744;2251.887405629716;2304.4238940264936;2308.90764369425;2311.133325003124;2314.606516892124;2313.3406979010983;2327.44141406063;2339.9002468839703;2347.95304093918;2354.682117914085;1923.557581756197;2285.1316499730165;2301.355620426283;2314.0975019742314;2315.548468048731;2265.66015097735;2275.260540324401;2283.486215601493;2294.19703103914;2297.023764899911;2300.068472268734;2318.886934271542;2321.4892723600783;2322.73288571813;2331.6968794056834;8300.584970751499;8311.459755115899;8369.163083691643;8416.284615807665;8428.45215061019;4596;4760.785764640565;4798.328234047236;4982.128127577659;5136.075506408845;2661.8003649726347;3049.1005153479778;3064.341166649181;3242.837858107085;3516.736244781626;1024.7130803375067;1068.5832525315134;1077.7305673581604;1082.3722241100143;1088.0968795899385;10404.68785936423;10504.947475262576;10711.78576428467;10715;10753.139748820076;3793.7534060286544;4206.326987681956;4371.9944412785235;4416.072624748838;4425.09285596452;8327.5003499789;8344.415890887702;8399.328053755686;8481.279091277187;8514.57427128641;4969.478204788517;4978.5270399312;4996.950030499648;5001.599872010231;5008.774605142705;4250.426192463978;4376.868693939124;4584.610308123847;4692.108499385175;4705.6470764692785;3519.806410647439;3576.535050443426;3620.855165793339;3645.2345007548183;3666.4500324950936;1132.671525257666;1138.6299452677747;1146.2262972493534;1154.8556430446195;1157.3229660648615;852.53110789067;861.4271509446273;880.6697488441835;909.1769110888697;920.4293338130402;5266.3943646480375;5367.812126575629;5543.972280138573;5563.360213575491;5611.382747897728;414.7076311200657;430.74370749404056;449.04353726562215;449.2407830765995;450.24583822097856;3036.544518322225;3750.006248344191;3819.388897776342;3832.9808350958065;3840.9231815363537;9304.53477326131;9735.853962190573;9749.122578967927;9798.069183427562;9986.700398988041;9907.900920990698;9927.453990030474;9934.354266972623;9941.950290248502;9953.004699530135;6584.341565843377;7537.8115547111765;7843.37253019757;7843.843123137505;7862.410319225966;5316.840494785162;5394.2448057272795;5418.8891277287275;5515.393306736176;5537.307836520435;9182.724518264462;9254.907450925673;9280.907190928005;9320.673776417792;9327.160555550034;9219.86170207434;9322.347435679438;9467.147956683932;9582.520873956413;9586.808263834544;5616.157576363578;6415.871682566416;6510.381513756186;6605.438537724347;6778.2205046420295;7798.610069496389;7876.921230787848;7944.563049032358;7990.720324788605;7991.800204994922;6982.51122421446;7103.396211321889;7133.6076515790055;7142.678579463833;7239.819004524929;7002.124734408199;7012.614306213207;7029.472789540907;7046.682899269443;7131.572105673677;6465.838354041187;6504.869902602016;6571.211454625475;6591.307912669101;6594.604323740591;4327.199468098412;4526.615237704832;4656.8602941911795;4708.317293992421;4817.759112044454;3814.7520411173186;3970.8411663533134;4143.295639741312;4264.786760662017;4480.865574032784 22:21:05 INFO - PROCESS | 1944 | |4;prototype.html;253.45254250818232;282.82486269550014;289.3605132656828;300.53717275395894;306.69637059311276;4555.88610284743;5277.551408130314;5350.705711185893;5381.838544843656;5429.348478182618;4220.36694495825;4375.8468453604055;4388.275934470808;4813.302071199674;5056.8482945511605;3626.4560315952554;3826.846926122959;3964.2666106770193;4040.818163182649;4070.7964601769795;3415.709664678493;3416.7095796857175;4060.573639767826;4072.287349713803;4082.6529744971713;3409;4061.3501839705655;4063.9593604064025;4072.36878283865;4083.5099788025477;3730.198007428389;3745.0637340664834;3970.940435893464;4102.917941641151;4116.629503344699;3467.4625433057918;3476.1309672581856;3480.6345333739846;3481.599616044137;3505.807180605091;5135.255387968724;5163.302954101224;5190.24741412493;5205.077247261133;5232.816851410183;4043;4366.978165109154;4562.840300589463;4575.245084561044;4596.563326483979;4795.448523419816;5243.632945693803;5475.151351540518;5502.779888804443;5547.694876781776;3753.2359790898236;3764.247150569897;3767.2653832502674;3771.057235691077;3787.110029143149;4900.338454308659;5133.66524703576;5163.922541161848;5781.653100813965;5905.881882362329;3130.765192610563;3831.4061320495366;3851.8459261629223;3917.4515567820526;3935.940960885589;3540.592831824373;3673.44898265257;3974.2448934702315;3993.341098718737;4003.539592946848;4567.748773817472;4591.920898588895;4617.353570500132;4626.37543931565;4672.434734363958;4734.644901632391;4763.3807605011125;5185.170372740344;5610.587447397658;5683.886322273532;1091.9344839309667;1121.315997241682;1247.4760600547793;1287.459267107817;1296.7406518696298;825.8554752918216;858.3133493205536;868.3964644572088;869.1873098652779;874.5190145419994;5163.586913046947;5338.039152952506;5393.946060539344;5477.4796394342475;5495.505404513613;4475.977620111878;5157.561607263349;5162.6902385856965;5167.819126330561;5900.315356512777;4729.858104256878;4779.187538118528;5149.9742501288;5201.349831271091;5424.240606315119;2496.662950501659;2649.470105978812;2695.5148073346986;2885.6248687670886;2910.7816913731554;4877.585405240523;5885.9987604214775;5887.558433117533;5914.733836977346;5923.571335752321;4911.87720306988;5200.761379475715;5283.260343551906;5422.0511410503;5591.776328946797;5203.14148165556;5401.864953376118;5478.863672686616;5837.270341207349;5876.207756634097;4797.760111994457;5551.306086739158;5560.165975103605;5583.812140894329;5604.6356986797355;4917.975410123069;5217.895642087213;5429.375621803385;5447.754851031634;5469.019384435413;5801.79693710735;5811.27359080115;5858.326292476249;5881.882362352643;5901.963822481237;3650.580183278957;3700.888973330804;3705.2404257127764;3751.5123034006497;3758.9060273493383;4852.660313778143;5390.97304513475;5466.496713403846;5477.521773673826;5482.80810171642;4356.673249506236;4620.445546534437;5310.761015754223;5312.867178320573;5351.839444816662;4473.865784026484;5145.434002259673;5202.97398513005;5231;5267.947320526746;4486.461624605068;4806.663533552618;4991.675541089818;5137.972405518986;5162.716050617102;4979.726115063561;5302.973485132549;5325.896270972867;5479.53423958968;5613.438656134354;5076.822311219237;5496.890062198813;5500.614956952975;5551.945780061403;5558.805441809679 22:21:05 INFO - PROCESS | 1944 | |5;yui.html;653.4249860123091;677.2144312597388;690.3510699942054;723.4321057969495;768.581123287808;14180.574582762521;14405.855941440583;14647.414103435876;14753.11481311122;14782.556523304318;13439.462421503129;13499.325033748322;13586.932065339684;13624.795628065587;13630.591082267523;837.8910741603598;847.7753395350237;861.8060936289316;861.9094995025495;863.7754183912165;822.33390953328;829.4940086547201;855.9186877246683;862.3101518784978;869.3697069624535;745.4111252110853;885.7652722028668;890.5458216309664;893.6425429828056;895.3911340288602;883.3551507399602;885.2961895293257;885.6767279942839;886.4061079077034;889.3818795936794;622.6077571130204;627.328758228691;664.7905909638448;666.9666516674196;666.9899951500731;13597.184168949894;14137.646558836008;14210.573682789533;14263.072900261554;14273.072250303698;12906.419211135522;13355.666108347368;13355.732885342335;13374;13409.463621455228;11031.669049928514;11373.658790236306;11505.827412588818;11545.134114941413;12061.155719099666;3576.2847430514003;3583.3549961007006;3589.5333606631234;3673.045008297835;3706.777593344408;79985.60007199986;80064.59967700124;81148;81372;81861;10210.744731381626;10327.277090603586;10335;10633.043026127685;10815.134789216843;18844.528886777665;20053.29813456522;20067.698984515246;20111.597768044558;20144.093515791825;7651.464397492122;7744.922550774533;7794.181610930884;7834;7869.134395216522;4243.469566304212;4355.4337936068105;4357.215701173819;4358.389825424443;4433.689641725048;3283.688049635281;3373.797572145679;3403.2002479417315;3405.557277553902;3412.1640198151613;598.7664810723821;614.913912052313;631.4348657951108;637.5505268785495;640.3852301790333;590.6279044202125;653.8071268975664;661.6096503063216;678.1794029224595;686.2005763285816;8250.257476826995;8444;8780.473171609723;9101.544922753836;9323.487208203615;580.6283978253921;604.5375287904757;658.7628453756644;665.4543274514948;673.9730410783513;4273.423087883159;4423.955760442419;4425.380446737459;4443.488998765119;4551.362809206714;89863.55068224747;102020;103416;106007;113498;68346.65826670834;73903.26096739071;93358.53320733353;100449.46629002853;102405;68096.6595167021;87228.56385718031;89605;90059.54970225238;91196.08803912009;7962.920370796218;8078.515289082674;8673.653053877773;8675.089115642766;8887;49160;56450.71774641101;56652;57824.71087644535;58003;71465.6426717863;72277;74451.67160791393;75065.24934750583;76031.61984190265;11148.219624626523;11419.828702569303;12034.097442691658;12249.816252756038;12579.182353147016;13539.458421663418;13561.86438135606;14095.718085638026;14266.785998209827;14500.85499144995;12537.811932820832;12604.32567452353;12692.982761449673;12753.61739147827;13052.282124482865;13064.281464519543;13182.198584721038;13232.536861209812;13283.468661253442;13825;12816.295103769382;12822.23066616006;13239.20564766117;13269.734605308033;13280.53518126861;9010.729678109667;10080.596776129167;10848.915108489404;10854.837177442187;11124.388158651353;10678.786424271315;10986.890131098906;11224.494897729459;11296.548138074386;11400.201985860911 22:21:05 INFO - PROCESS | 1944 | __end_tp_report 22:21:05 INFO - PROCESS | 1944 | __start_cc_report 22:21:05 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,366 22:21:05 INFO - PROCESS | 1944 | __end_cc_report 22:21:05 INFO - PROCESS | 1944 | __startTimestamp1479882065371__endTimestamp 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | ------- Summary: start ------- 22:21:05 INFO - PROCESS | 1944 | Number of tests: 6 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5483.75 Median:5713.77 stddev:646.05 (11.3%) stddev-sans-first:623.94 22:21:05 INFO - PROCESS | 1944 | Values: 3255.7 3292.4 3334.8 3340.2 3354.8 4454.9 5370.4 5436.8 5460.0 5504.2 4465.6 5464.8 5549.7 5561.7 5597.0 4977.7 5020.1 5054.1 5055.1 5192.6 4514.8 4876.4 5059.9 5149.4 5223.9 4108.4 5113.0 5152.8 5189.4 5190.2 5004.3 5047.8 5048.3 5075.1 5078.9 4812.0 4993.2 5009.3 5024.6 5032.0 4454.9 5196.2 5457.3 5491.3 5498.3 5986.4 5988.1 5999.1 6031.8 6064.5 6031.3 6039.7 6061.7 6066.3 6067.4 5472.3 5513.4 5531.8 5564.3 5586.0 6112.0 6128.8 6135.9 6160.5 6173.7 4957.7 5855.8 5896.5 5906.8 5916.7 6002.8 6015.8 6025.4 6040.3 6048.4 5771.6 5825.9 5828.4 5840.2 5864.6 5879.5 5950.4 5970.2 5982.2 6027.2 4060.3 4729.9 4746.5 4776.6 4784.4 4134.3 4226.4 4247.4 4248.6 4299.4 5182.2 5407.3 5502.3 5691.8 5804.9 6020.9 6055.3 6064.6 6066.3 6106.5 5713.8 5744.6 5762.1 5776.3 5783.7 5077.2 5129.7 5186.3 5189.5 5191.8 6131.1 6136.4 6174.3 6192.3 6211.5 6001.5 6005.1 6008.7 6031.0 6059.6 6108.4 6179.1 6190.1 6226.1 6227.8 5783.6 5825.4 5837.6 5883.3 5895.9 4792.3 4803.0 5941.6 5957.0 6014.3 5710.3 5795.5 5797.8 5805.1 5807.4 5751.7 5773.3 5783.8 5836.0 5928.1 5509.3 5723.5 5726.7 5727.1 5753.3 5262.9 5516.8 5703.1 5735.7 5804.0 5539.9 5635.2 5659.0 5659.9 5680.7 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:69172.64 Median:35873.69 stddev:153669.23 (428.4%) stddev-sans-first:154013.73 22:21:05 INFO - PROCESS | 1944 | Values: 468.3 516.8 520.5 520.7 526.0 37427.0 38873.4 38928.4 39010.6 40754.8 32471.8 39689.4 39966.4 40087.0 40185.6 8493.5 9161.7 9368.3 10804.4 11192.7 11114.4 11159.8 11173.8 11192.2 11201.6 11177.9 11216.3 11222.6 11238.0 11296.4 11149.0 11184.7 11193.7 11194.1 11240.3 9372.8 9595.9 10563.6 11021.6 11090.6 38160.6 38456.2 38623.8 38640.8 38648.6 3284.7 3497.4 3682.6 3908.6 3969.3 28620.7 29555.4 29631.8 29671.7 29969.7 3230.5 3239.6 3243.7 3331.0 3352.2 462327.0 467396.0 469478.0 473083.0 478866.0 46649.8 55317.7 55332.4 55440.4 55484.0 61736.4 62046.0 62194.0 62356.1 62486.7 15504.9 15896.4 15920.0 16034.8 16067.8 11115.7 11291.3 12065.6 12179.8 12267.9 18072.7 18247.8 18323.4 18340.8 18341.0 6555.6 7780.2 7797.0 7826.6 7890.8 839.5 840.6 919.0 945.7 972.7 26228.7 28902.0 29385.0 29394.0 29801.6 912.0 1035.5 1058.2 1147.0 1162.2 11574.2 12516.0 13875.5 15060.0 15361.9 666573.0 668291.0 668444.0 670506.0 674211.0 54348.0 54917.2 54995.0 55013.6 55152.8 1302.5 1352.3 1365.2 1370.7 1377.5 27889.7 28740.4 29013.3 29311.8 29378.3 47722.3 48074.8 48218.5 48314.5 48365.0 514155.0 574950.0 576845.0 577930.0 579204.0 22769.7 23408.6 24545.8 25465.2 25640.9 42994.0 43361.0 43578.6 43723.0 44181.8 30999.0 32430.0 32496.4 36794.0 37081.4 36260.0 36420.6 37049.3 37375.1 37564.6 35706.8 36040.6 36182.0 36644.5 36980.8 31636.7 32934.7 32941.7 33016.5 33043.3 22575.7 24431.3 24538.6 24972.0 25027.9 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:32839.78 Median:2528.18 stddev:89061.56 (3522.8%) stddev-sans-first:89277.97 22:21:05 INFO - PROCESS | 1944 | Values: 342.4 344.5 374.0 380.5 387.2 16075.4 18085.4 18368.2 19768.5 19906.5 16776.4 19673.8 19844.2 19850.9 20182.9 5409.9 5776.4 5784.5 5784.9 5787.3 5510.6 5644.0 5814.7 5897.0 5923.5 5796.3 5889.5 5901.7 5903.6 5926.0 4930.3 5672.0 5870.5 5884.4 5905.3 4179.2 5639.6 5654.7 5692.7 5708.3 12886.4 12937.4 12945.7 13141.0 13263.2 2026.6 2331.0 2451.5 2604.8 2739.1 9452.4 10050.4 10380.3 11752.2 15394.2 1818.2 1841.3 1875.2 1890.7 1898.4 114864.0 123254.0 124117.0 125534.7 125803.0 11265.7 11545.7 11557.4 11611.4 11721.6 12177.9 14458.0 14458.0 14521.9 14525.9 7492.3 8296.8 8394.2 8476.0 8505.2 8146.8 8162.4 8181.5 8348.4 8370.9 4386.6 4579.5 4582.9 4605.3 4622.5 1907.4 1956.7 1960.0 1964.8 1965.5 824.6 884.5 887.9 914.4 1025.3 15423.7 18099.9 19488.3 20284.7 22517.7 810.6 909.2 925.3 926.9 941.0 9424.9 10222.6 10300.3 10653.5 10769.5 492407.0 495088.0 498115.5 571547.0 576470.0 107328.0 110789.0 113372.4 132006.0 142060.0 921.4 977.4 993.2 993.8 996.2 18931.5 19261.4 19272.0 19281.7 19381.6 56084.4 56706.7 57824.7 58243.4 58687.0 83740.2 84603.6 85434.1 86188.1 86836.0 8026.4 8029.6 8217.3 8225.5 8292.9 12669.0 12877.8 12894.8 13107.5 13136.3 10988.1 11298.7 11381.8 11517.7 11535.0 10734.4 11072.1 11106.6 11407.5 11463.7 11344.6 11477.6 11647.8 11775.9 11814.8 6833.5 7328.6 7342.9 7591.8 8464.4 9890.3 10463.1 11214.2 11366.4 11596.8 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5269.76 Median:4989.54 stddev:2942.95 (59.0%) stddev-sans-first:2936.95 22:21:05 INFO - PROCESS | 1944 | Values: 1415.6 1461.8 1634.4 1775.8 1816.2 7459.2 7646.2 8033.2 8429.2 8439.2 7901.0 8120.1 8123.7 8309.2 8316.8 2251.9 2304.4 2308.9 2311.1 2314.6 2313.3 2327.4 2339.9 2348.0 2354.7 1923.6 2285.1 2301.4 2314.1 2315.5 2265.7 2275.3 2283.5 2294.2 2297.0 2300.1 2318.9 2321.5 2322.7 2331.7 8300.6 8311.5 8369.2 8416.3 8428.5 4596.0 4760.8 4798.3 4982.1 5136.1 2661.8 3049.1 3064.3 3242.8 3516.7 1024.7 1068.6 1077.7 1082.4 1088.1 10404.7 10504.9 10711.8 10715.0 10753.1 3793.8 4206.3 4372.0 4416.1 4425.1 8327.5 8344.4 8399.3 8481.3 8514.6 4969.5 4978.5 4997.0 5001.6 5008.8 4250.4 4376.9 4584.6 4692.1 4705.6 3519.8 3576.5 3620.9 3645.2 3666.5 1132.7 1138.6 1146.2 1154.9 1157.3 852.5 861.4 880.7 909.2 920.4 5266.4 5367.8 5544.0 5563.4 5611.4 414.7 430.7 449.0 449.2 450.2 3036.5 3750.0 3819.4 3833.0 3840.9 9304.5 9735.9 9749.1 9798.1 9986.7 9907.9 9927.5 9934.4 9942.0 9953.0 6584.3 7537.8 7843.4 7843.8 7862.4 5316.8 5394.2 5418.9 5515.4 5537.3 9182.7 9254.9 9280.9 9320.7 9327.2 9219.9 9322.3 9467.1 9582.5 9586.8 5616.2 6415.9 6510.4 6605.4 6778.2 7798.6 7876.9 7944.6 7990.7 7991.8 6982.5 7103.4 7133.6 7142.7 7239.8 7002.1 7012.6 7029.5 7046.7 7131.6 6465.8 6504.9 6571.2 6591.3 6594.6 4327.2 4526.6 4656.9 4708.3 4817.8 3814.8 3970.8 4143.3 4264.8 4480.9 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4413.39 Median:4914.93 stddev:1352.82 (27.5%) stddev-sans-first:1320.10 22:21:05 INFO - PROCESS | 1944 | Values: 253.5 282.8 289.4 300.5 306.7 4555.9 5277.6 5350.7 5381.8 5429.3 4220.4 4375.8 4388.3 4813.3 5056.8 3626.5 3826.8 3964.3 4040.8 4070.8 3415.7 3416.7 4060.6 4072.3 4082.7 3409.0 4061.4 4064.0 4072.4 4083.5 3730.2 3745.1 3970.9 4102.9 4116.6 3467.5 3476.1 3480.6 3481.6 3505.8 5135.3 5163.3 5190.2 5205.1 5232.8 4043.0 4367.0 4562.8 4575.2 4596.6 4795.4 5243.6 5475.2 5502.8 5547.7 3753.2 3764.2 3767.3 3771.1 3787.1 4900.3 5133.7 5163.9 5781.7 5905.9 3130.8 3831.4 3851.8 3917.5 3935.9 3540.6 3673.4 3974.2 3993.3 4003.5 4567.7 4591.9 4617.4 4626.4 4672.4 4734.6 4763.4 5185.2 5610.6 5683.9 1091.9 1121.3 1247.5 1287.5 1296.7 825.9 858.3 868.4 869.2 874.5 5163.6 5338.0 5393.9 5477.5 5495.5 4476.0 5157.6 5162.7 5167.8 5900.3 4729.9 4779.2 5150.0 5201.3 5424.2 2496.7 2649.5 2695.5 2885.6 2910.8 4877.6 5886.0 5887.6 5914.7 5923.6 4911.9 5200.8 5283.3 5422.1 5591.8 5203.1 5401.9 5478.9 5837.3 5876.2 4797.8 5551.3 5560.2 5583.8 5604.6 4918.0 5217.9 5429.4 5447.8 5469.0 5801.8 5811.3 5858.3 5881.9 5902.0 3650.6 3700.9 3705.2 3751.5 3758.9 4852.7 5391.0 5466.5 5477.5 5482.8 4356.7 4620.4 5310.8 5312.9 5351.8 4473.9 5145.4 5203.0 5231.0 5267.9 4486.5 4806.7 4991.7 5138.0 5162.7 4979.7 5303.0 5325.9 5479.5 5613.4 5076.8 5496.9 5500.6 5551.9 5558.8 22:21:05 INFO - PROCESS | 1944 | 22:21:05 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:19932.52 Median:4424.67 stddev:28682.53 (648.2%) stddev-sans-first:28726.46 22:21:05 INFO - PROCESS | 1944 | Values: 653.4 677.2 690.4 723.4 768.6 14180.6 14405.9 14647.4 14753.1 14782.6 13439.5 13499.3 13586.9 13624.8 13630.6 837.9 847.8 861.8 861.9 863.8 822.3 829.5 855.9 862.3 869.4 745.4 885.8 890.5 893.6 895.4 883.4 885.3 885.7 886.4 889.4 622.6 627.3 664.8 667.0 667.0 13597.2 14137.6 14210.6 14263.1 14273.1 12906.4 13355.7 13355.7 13374.0 13409.5 11031.7 11373.7 11505.8 11545.1 12061.2 3576.3 3583.4 3589.5 3673.0 3706.8 79985.6 80064.6 81148.0 81372.0 81861.0 10210.7 10327.3 10335.0 10633.0 10815.1 18844.5 20053.3 20067.7 20111.6 20144.1 7651.5 7744.9 7794.2 7834.0 7869.1 4243.5 4355.4 4357.2 4358.4 4433.7 3283.7 3373.8 3403.2 3405.6 3412.2 598.8 614.9 631.4 637.6 640.4 590.6 653.8 661.6 678.2 686.2 8250.3 8444.0 8780.5 9101.5 9323.5 580.6 604.5 658.8 665.5 674.0 4273.4 4424.0 4425.4 4443.5 4551.4 89863.6 102020.0 103416.0 106007.0 113498.0 68346.7 73903.3 93358.5 100449.5 102405.0 68096.7 87228.6 89605.0 90059.5 91196.1 7962.9 8078.5 8673.7 8675.1 8887.0 49160.0 56450.7 56652.0 57824.7 58003.0 71465.6 72277.0 74451.7 75065.2 76031.6 11148.2 11419.8 12034.1 12249.8 12579.2 13539.5 13561.9 14095.7 14266.8 14500.9 12537.8 12604.3 12693.0 12753.6 13052.3 13064.3 13182.2 13232.5 13283.5 13825.0 12816.3 12822.2 13239.2 13269.7 13280.5 9010.7 10080.6 10848.9 10854.8 11124.4 10678.8 10986.9 11224.5 11296.5 11400.2 22:21:05 INFO - PROCESS | 1944 | -------- Summary: end -------- 22:21:05 INFO - PROCESS | 1944 | 22:21:06 INFO - TEST-INFO | 1944: exit 0 22:21:06 INFO - TEST-OK | dromaeo_css | took 1117947ms 22:21:06 INFO - TEST-START | kraken 22:21:06 INFO - operating with platform_type : mac_ 22:21:06 INFO - Initialising browser for kraken test... 22:21:06 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpHKokSS/profile http://localhost:51686/getInfo.html) 22:21:09 INFO - PROCESS | 1949 | 2016-11-22 22:21:09.131 plugin-container[1950:18050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 22:21:09 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:21:09 INFO - PROCESS | 1949 | 2016-11-22 22:21:09.134 plugin-container[1950:18050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:21:09 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:21:09 INFO - PROCESS | 1949 | 2016-11-22 22:21:09.134 plugin-container[1950:18050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:21:09 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 22:21:09 INFO - PROCESS | 1949 | colorDepth:24 22:21:09 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 22:21:09 INFO - PROCESS | 1949 | __metrics 22:21:09 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 22:21:10 INFO - TEST-INFO | 1949: exit 0 22:21:10 INFO - Browser initialized. 22:21:10 INFO - Running cycle 1/1 for kraken test... 22:21:10 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpHKokSS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:12 INFO - PROCESS | 1951 | 2016-11-22 22:21:12.763 plugin-container[1952:18164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 22:21:12 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:21:12 INFO - PROCESS | 1951 | 2016-11-22 22:21:12.764 plugin-container[1952:18164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.CFPasteboardClient' 22:21:12 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:21:12 INFO - PROCESS | 1951 | 2016-11-22 22:21:12.764 plugin-container[1952:18164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:21:22 INFO - PROCESS | 1951 | RSS: Main: 241799168 22:21:22 INFO - PROCESS | 1951 | 22:21:25 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-beat-detection) 22:21:26 INFO - PROCESS | 1951 | RSS: Main: 253870080 22:21:26 INFO - PROCESS | 1951 | 22:21:29 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-dft) 22:21:29 INFO - PROCESS | 1951 | RSS: Main: 261480448 22:21:29 INFO - PROCESS | 1951 | 22:21:33 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-fft) 22:21:33 INFO - PROCESS | 1951 | RSS: Main: 264253440 22:21:33 INFO - PROCESS | 1951 | 22:21:35 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-oscillator) 22:21:36 INFO - PROCESS | 1951 | RSS: Main: 265342976 22:21:36 INFO - PROCESS | 1951 | 22:21:38 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:21:39 INFO - PROCESS | 1951 | RSS: Main: 265781248 22:21:39 INFO - PROCESS | 1951 | 22:21:41 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-darkroom) 22:21:42 INFO - PROCESS | 1951 | RSS: Main: 272400384 22:21:42 INFO - PROCESS | 1951 | 22:21:45 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:46 INFO - PROCESS | 1951 | RSS: Main: 271548416 22:21:46 INFO - PROCESS | 1951 | 22:21:49 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51686/tests/kraken/driver.html?testName=json-parse-financial) 22:21:49 INFO - PROCESS | 1951 | RSS: Main: 271859712 22:21:49 INFO - PROCESS | 1951 | 22:21:51 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51686/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:52 INFO - PROCESS | 1951 | RSS: Main: 270983168 22:21:52 INFO - PROCESS | 1951 | 22:21:54 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:54 INFO - PROCESS | 1951 | RSS: Main: 271065088 22:21:54 INFO - PROCESS | 1951 | 22:21:56 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:57 INFO - PROCESS | 1951 | RSS: Main: 270987264 22:21:57 INFO - PROCESS | 1951 | 22:22:00 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:22:01 INFO - PROCESS | 1951 | RSS: Main: 271290368 22:22:01 INFO - PROCESS | 1951 | 22:22:04 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:22:05 INFO - PROCESS | 1951 | RSS: Main: 271065088 22:22:05 INFO - PROCESS | 1951 | 22:22:06 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51686/tests/kraken/driver.html?testName=ai-astar) 22:22:07 INFO - PROCESS | 1951 | RSS: Main: 272809984 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | __start_tp_report 22:22:07 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 22:22:07 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 22:22:07 INFO - PROCESS | 1951 | |i|pagename|runs| 22:22:07 INFO - PROCESS | 1951 | |0;ai-astar;106;118;118;119;117;118;118;119;118;116 22:22:07 INFO - PROCESS | 1951 | |1;audio-beat-detection;148;117;126;149;116;116;117;121;168;150 22:22:07 INFO - PROCESS | 1951 | |2;audio-dft;207;228;258;259;197;199;197;198;223;213 22:22:07 INFO - PROCESS | 1951 | |3;audio-fft;86;59;61;79;72;50;50;52;91;86 22:22:07 INFO - PROCESS | 1951 | |4;audio-oscillator;92;72;85;71;70;69;78;81;102;100 22:22:07 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;89;90;129;85;98;86;84;86;90;85 22:22:07 INFO - PROCESS | 1951 | |6;imaging-darkroom;99;115;160;98;99;99;99;99;151;171 22:22:07 INFO - PROCESS | 1951 | |7;imaging-desaturate;93;101;87;150;146;92;96;114;151;91 22:22:07 INFO - PROCESS | 1951 | |8;json-parse-financial;55;56;56;56;59;59;57;57;58;57 22:22:07 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;72;68;81;82;74;72;72;73;74;80 22:22:07 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;85;88;84;87;83;99;111;82;78;80 22:22:07 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;166;142;158;165;163;176;166;149;191;171 22:22:07 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;177;172;174;176;176;176;176;174;175;173 22:22:07 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;71;69;69;92;91;91;72;89;89;77 22:22:07 INFO - PROCESS | 1951 | __end_tp_report 22:22:07 INFO - PROCESS | 1951 | __start_cc_report 22:22:07 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,938 22:22:07 INFO - PROCESS | 1951 | __end_cc_report 22:22:07 INFO - PROCESS | 1951 | __startTimestamp1479882127166__endTimestamp 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | ------- Summary: start ------- 22:22:07 INFO - PROCESS | 1951 | Number of tests: 14 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:116.70 Median:118.00 stddev:3.86 (3.3%) stddev-sans-first:0.93 22:22:07 INFO - PROCESS | 1951 | Values: 106.0 118.0 118.0 119.0 117.0 118.0 118.0 119.0 118.0 116.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:137.00 stddev:19.08 (13.9%) stddev-sans-first:19.43 22:22:07 INFO - PROCESS | 1951 | Values: 148.0 117.0 126.0 149.0 116.0 116.0 117.0 121.0 168.0 150.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:217.90 Median:218.00 stddev:24.03 (11.0%) stddev-sans-first:25.16 22:22:07 INFO - PROCESS | 1951 | Values: 207.0 228.0 258.0 259.0 197.0 199.0 197.0 198.0 223.0 213.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:68.60 Median:75.50 stddev:16.14 (21.4%) stddev-sans-first:15.84 22:22:07 INFO - PROCESS | 1951 | Values: 86.0 59.0 61.0 79.0 72.0 50.0 50.0 52.0 91.0 86.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:75.00 stddev:12.40 (16.5%) stddev-sans-first:12.61 22:22:07 INFO - PROCESS | 1951 | Values: 92.0 72.0 85.0 71.0 70.0 69.0 78.0 81.0 102.0 100.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:87.50 stddev:13.56 (15.5%) stddev-sans-first:14.34 22:22:07 INFO - PROCESS | 1951 | Values: 89.0 90.0 129.0 85.0 98.0 86.0 84.0 86.0 90.0 85.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:119.00 Median:99.00 stddev:29.56 (29.9%) stddev-sans-first:30.46 22:22:07 INFO - PROCESS | 1951 | Values: 99.0 115.0 160.0 98.0 99.0 99.0 99.0 99.0 151.0 171.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:112.10 Median:89.00 stddev:26.52 (29.8%) stddev-sans-first:27.21 22:22:07 INFO - PROCESS | 1951 | Values: 93.0 101.0 87.0 150.0 146.0 92.0 96.0 114.0 151.0 91.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.33 (2.3%) stddev-sans-first:1.20 22:22:07 INFO - PROCESS | 1951 | Values: 55.0 56.0 56.0 56.0 59.0 59.0 57.0 57.0 58.0 57.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:74.00 stddev:4.61 (6.2%) stddev-sans-first:4.78 22:22:07 INFO - PROCESS | 1951 | Values: 72.0 68.0 81.0 82.0 74.0 72.0 72.0 73.0 74.0 80.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:84.50 stddev:10.00 (11.8%) stddev-sans-first:10.56 22:22:07 INFO - PROCESS | 1951 | Values: 85.0 88.0 84.0 87.0 83.0 99.0 111.0 82.0 78.0 80.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:164.70 Median:166.00 stddev:13.63 (8.2%) stddev-sans-first:14.45 22:22:07 INFO - PROCESS | 1951 | Values: 166.0 142.0 158.0 165.0 163.0 176.0 166.0 149.0 191.0 171.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:176.00 stddev:1.60 (0.9%) stddev-sans-first:1.50 22:22:07 INFO - PROCESS | 1951 | Values: 177.0 172.0 174.0 176.0 176.0 176.0 176.0 174.0 175.0 173.0 22:22:07 INFO - PROCESS | 1951 | 22:22:07 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.00 Median:89.00 stddev:10.19 (11.4%) stddev-sans-first:10.14 22:22:07 INFO - PROCESS | 1951 | Values: 71.0 69.0 69.0 92.0 91.0 91.0 72.0 89.0 89.0 77.0 22:22:07 INFO - PROCESS | 1951 | -------- Summary: end -------- 22:22:07 INFO - PROCESS | 1951 | 22:22:08 INFO - TEST-INFO | 1951: exit 0 22:22:08 INFO - TEST-OK | kraken | took 62181ms 22:22:08 INFO - SUITE-END | took 1180s 22:22:08 INFO - Completed test suite (00:19:40) 22:22:08 INFO - javascript benchmark 22:22:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7023.1027077977815, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3255.6744325567433, 3292.3744488547172, 3334.7661365294844, 3340.2317466982595, 3354.798712077274, 4454.933176002362, 5370.355557333126, 5436.75534600943, 5460.0, 5504.2018907258425, 4465.553444655533, 5464.754086066127, 5549.667019978794, 5561.694106824123, 5597.048501754702, 4977.651564390494, 5020.146575082226, 5054.115529782273, 5055.115354812911, 5192.558632516222, 4514.819407223724, 4876.439209490918, 5059.898802023958, 5149.40781810091, 5223.869403264911, 4108.383742438658, 5112.97443512782, 5152.84541463753, 5189.42916279209, 5190.195519694454, 5004.299398084271, 5047.823326183566, 5048.318477005594, 5075.111855425286, 5078.923816142761, 4811.9518804812205, 4993.176125939216, 5009.298698182258, 5024.572911302544, 5032.04391165681, 4454.910901781946, 5196.246544251062, 5457.263269458612, 5491.313585801775, 5498.3127109111365, 5986.431289027535, 5988.101784732282, 5999.100134979744, 6031.758729650809, 6064.484518815906, 6031.306399764039, 6039.667818269993, 6061.696915154269, 6066.332703402622, 6067.393260673942, 5472.315960504937, 5513.365962914316, 5531.751071201806, 5564.33228012637, 5586.0, 6111.98679349419, 6128.80869161996, 6135.877282454326, 6160.507159427235, 6173.722182501798, 4957.7273249971595, 5855.765769369177, 5896.469317761422, 5906.793262235801, 5916.704164791743, 6002.789902353396, 6015.789447369321, 6025.3974602540275, 6040.305364883006, 6048.425399587032, 5771.5571107223195, 5825.941740582624, 5828.388019257917, 5840.153177789197, 5864.560157988168, 5879.5002424794375, 5950.375210602911, 5970.223870896755, 5982.2223110996165, 6027.156198132265, 4060.3097473428998, 4729.864832440189, 4746.501617330199, 4776.5701086902345, 4784.40194975628, 4134.276501612206, 4226.429432026698, 4247.426597409372, 4248.575142485726, 4299.419578356944, 5182.170852663556, 5407.270018547524, 5502.257195278667, 5691.8292451226525, 5804.88390232193, 6020.879582408327, 6055.333913269536, 6064.605800622945, 6066.272047354258, 6106.542009349317, 5713.771449141987, 5744.569157313218, 5762.078067509262, 5776.306843178845, 5783.71081445926, 5077.213031980049, 5129.743512824344, 5186.299849520342, 5189.506996835295, 5191.766370513336, 6131.132004888217, 6136.427173609184, 6174.289956654862, 6192.287886892975, 6211.503079753699, 6001.4598686116715, 6005.099235114768, 6008.669523176267, 6030.969845150747, 6059.6364218147055, 6108.419700128481, 6179.104029915727, 6190.102435146789, 6226.097215903578, 6227.782027629011, 5783.624064435799, 5825.446582574648, 5837.562182836389, 5883.264591926009, 5895.852603684943, 4792.329073929723, 4803.0, 5941.643501389931, 5957.0, 6014.278286605635, 5710.2576665033275, 5795.536357091507, 5797.797077102281, 5805.129230615442, 5807.419258074159, 5751.654900705971, 5773.307203135482, 5783.7975670853, 5835.9708201458725, 5928.110783382527, 5509.256250406304, 5723.456271654352, 5726.713664316851, 5727.055035919193, 5753.280839895013, 5262.89479209355, 5516.834494965157, 5703.144528320785, 5735.684537350486, 5803.984302747087, 5539.916901246565, 5635.211070450058, 5659.0, 5659.915101273402, 5680.7443665036], "value": 5444.484687025611, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.30924386529875, 516.7932826869252, 520.4977197004889, 520.7240162713761, 526.0426024635165, 37427.0, 38873.41689874651, 38928.416073758846, 39010.609893901084, 40754.79622601883, 32471.83764081182, 39689.404658930136, 39966.40050399247, 40086.997825054386, 40185.59814401855, 8493.532855692934, 9161.679341223058, 9368.34421590489, 10804.405757683324, 11192.720181995475, 11114.444277786119, 11159.776804463905, 11173.776524469506, 11192.216544841864, 11201.60794372197, 11177.888221117766, 11216.270942388719, 11222.607208747695, 11238.0, 11296.435178241096, 11149.0, 11184.664460066211, 11193.66419007431, 11194.10447164225, 11240.325580465116, 9372.765680858032, 9595.944446110921, 10563.63027294041, 11021.614243501439, 11090.55637774488, 38160.61839381598, 38456.230875382615, 38623.8068809657, 38640.80679596612, 38648.61351386478, 3284.671532846696, 3497.405441075023, 3682.576503702082, 3908.5700572936953, 3969.28552860485, 28620.713792862014, 29555.408891822044, 29631.814727410878, 29671.70328296711, 29969.70030299691, 3230.4508233600345, 3239.611246650417, 3243.708066274047, 3331.0, 3352.228987332927, 462327.0, 467395.98906016775, 469478.0, 473083.0, 478866.0, 46649.7667511667, 55317.723411382685, 55332.44667553273, 55440.44559554433, 55484.0, 61736.38263617396, 62046.0, 62194.0, 62356.06465903015, 62486.68756656188, 15504.922475387551, 15896.443624473088, 15920.044797312199, 16034.839651603568, 16067.839321606869, 11115.72210694739, 11291.322520648788, 12065.638030858912, 12179.756404871852, 12267.877321226852, 18072.728909066376, 18247.817521824876, 18323.35868244605, 18340.81659183391, 18341.0, 6555.578823505964, 7780.221977802287, 7796.961015194887, 7826.5520878637635, 7890.802729931683, 839.5088873009236, 840.5671079394117, 918.9908100918905, 945.7304668169538, 972.7324985628867, 26228.73771262301, 28902.0, 29385.0, 29393.971211007512, 29801.552976705367, 911.9726408207632, 1035.5081336365317, 1058.216919479595, 1146.9598564050218, 1162.1574358589955, 11574.247673901169, 12516.0, 13875.514356997659, 15060.021098628773, 15361.924665273547, 666573.0, 668291.0, 668444.0, 670506.0, 674211.0, 54348.0, 54917.1762423564, 54995.0, 55013.62465938384, 55152.7604877536, 1302.4790083966489, 1352.3373546961918, 1365.1945352242224, 1370.7258548290183, 1377.4696741754296, 27889.721102789525, 28740.42519149647, 29013.274668133465, 29311.827526898684, 29378.265543361587, 47722.28416573685, 48074.759626201645, 48218.5178148214, 48314.51685483241, 48365.0, 514155.0, 574950.0, 576845.0, 577930.0, 579204.0, 22769.658455122855, 23408.648870266617, 24545.754542454346, 25465.236042918743, 25640.871795640902, 42994.0, 43361.0, 43578.56421435745, 43723.0, 44181.77909110434, 30999.0, 32430.0, 32496.35007299888, 36794.0, 37081.443778343884, 36260.0, 36420.63579364278, 37049.259014819014, 37375.06562335963, 37564.62435375721, 35706.75026374064, 36040.63959360373, 36182.0, 36644.45033324556, 36980.81509592435, 31636.683633163375, 32934.670653293164, 32941.67058329386, 33016.50475242825, 33043.33913321672, 22575.66136507921, 24431.26706198817, 24538.631920521562, 24972.0, 25027.87486062558], "value": 19618.890651136135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.3597871979399, 344.49015457123454, 373.9551253849539, 380.51484357444264, 387.1559999201739, 16075.437359692412, 18085.367012154577, 18368.17343219555, 19768.505787355305, 19906.502337441572, 16776.41282555111, 19673.803261967376, 19844.206231750748, 19850.90820004899, 20182.89908550459, 5409.891802163964, 5776.39347868474, 5784.45047720468, 5784.855378615526, 5787.276590426197, 5510.614257002011, 5643.971780141093, 5814.738336774855, 5897.0, 5923.526117910556, 5796.3334216565, 5889.469947704706, 5901.704914754267, 5903.645781253117, 5925.970370148143, 4930.3344048553345, 5672.0, 5870.501007414375, 5884.352721200665, 5905.29136503618, 4179.247735407625, 5639.605227634067, 5654.74553645087, 5692.658440493542, 5708.315002199763, 12886.420111094929, 12937.35313234344, 12945.741085178244, 13141.0, 13263.204207747469, 2026.63520566298, 2331.0, 2451.5464639041834, 2604.817662763607, 2739.1371717908796, 9452.385594936308, 10050.396976181453, 10380.325278856926, 11752.177347585672, 15394.230288485644, 1818.1818181818235, 1841.254292011724, 1875.22178296007, 1890.6974884018628, 1898.4020033689344, 114864.0, 123254.0, 124117.0, 125534.74465255321, 125803.0, 11265.662030139109, 11545.653630391102, 11557.364344961108, 11611.419429028514, 11721.589744358907, 12177.878221217674, 14458.0, 14458.0, 14521.927390363191, 14525.92737036308, 7492.288232617892, 8296.751097467086, 8394.202550757669, 8476.0, 8505.23452889243, 8146.75559733209, 8162.428629995843, 8181.509109453452, 8348.415610907177, 8370.916290837136, 4386.583274588909, 4579.519150489217, 4582.885427864263, 4605.319058543674, 4622.46841613212, 1907.4468404162915, 1956.7260583518319, 1959.9902000489906, 1964.7838737738837, 1965.5282732144183, 824.5506199121493, 884.4648987362682, 887.9023307436177, 914.3553794574809, 1025.3335332034237, 15423.691526169414, 18099.91400515973, 19488.317908873403, 20284.695729564068, 22517.662235066487, 810.5501446697021, 909.2226146644524, 925.3430064654036, 926.9304802139867, 940.9952950235205, 9424.858627120599, 10222.568840362355, 10300.330478518872, 10653.467326633638, 10769.515371808131, 492407.0, 495088.0, 498115.50942245056, 571547.0, 576470.0, 107328.0, 110789.0, 113372.43313783378, 132006.0, 142060.0, 921.447131720962, 977.423320241047, 993.1756641987133, 993.7627653571205, 996.2080146283722, 18931.526711831764, 19261.422157335302, 19272.0, 19281.710774338677, 19381.612367752285, 56084.43915560792, 56706.716466417405, 57824.71087644535, 58243.4175658238, 58687.0, 83740.16259837324, 84603.5769821147, 85434.14565854262, 86188.13811861801, 86836.0, 8026.43814932949, 8029.638666260149, 8217.30152937007, 8225.547594882339, 8292.87560686578, 12669.0, 12877.8068328977, 12894.806577901527, 13107.475700972236, 13136.27750473733, 10988.066014388867, 11298.717532061764, 11381.829272560753, 11517.712057198638, 11535.0, 10734.355938643706, 11072.11423086135, 11106.61126860556, 11407.543698251977, 11463.656090317303, 11344.602938897098, 11477.598284060017, 11647.825282620937, 11775.882241177478, 11814.763704726029, 6833.45332373399, 7328.560286382834, 7342.926570734225, 7591.772246832604, 8464.365172612203, 9890.307678462439, 10463.05832475081, 11214.15893807984, 11366.431678416211, 11596.826047609462], "value": 9304.723253599486, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.6390120519268, 1461.846506116858, 1634.3707672546075, 1775.840174384305, 1816.2008716164878, 7459.179490256068, 7646.235376462351, 8033.196680331964, 8429.241368276855, 8439.198276163763, 7901.0, 8120.147384524614, 8123.715669951553, 8309.169083091689, 8316.833663326744, 2251.887405629716, 2304.4238940264936, 2308.90764369425, 2311.133325003124, 2314.606516892124, 2313.3406979010983, 2327.44141406063, 2339.9002468839703, 2347.95304093918, 2354.682117914085, 1923.557581756197, 2285.1316499730165, 2301.355620426283, 2314.0975019742314, 2315.548468048731, 2265.66015097735, 2275.260540324401, 2283.486215601493, 2294.19703103914, 2297.023764899911, 2300.068472268734, 2318.886934271542, 2321.4892723600783, 2322.73288571813, 2331.6968794056834, 8300.584970751499, 8311.459755115899, 8369.163083691643, 8416.284615807665, 8428.45215061019, 4596.0, 4760.785764640565, 4798.328234047236, 4982.128127577659, 5136.075506408845, 2661.8003649726347, 3049.1005153479778, 3064.341166649181, 3242.837858107085, 3516.736244781626, 1024.7130803375067, 1068.5832525315134, 1077.7305673581604, 1082.3722241100143, 1088.0968795899385, 10404.68785936423, 10504.947475262576, 10711.78576428467, 10715.0, 10753.139748820076, 3793.7534060286544, 4206.326987681956, 4371.9944412785235, 4416.072624748838, 4425.09285596452, 8327.5003499789, 8344.415890887702, 8399.328053755686, 8481.279091277187, 8514.57427128641, 4969.478204788517, 4978.5270399312, 4996.950030499648, 5001.599872010231, 5008.774605142705, 4250.426192463978, 4376.868693939124, 4584.610308123847, 4692.108499385175, 4705.6470764692785, 3519.806410647439, 3576.535050443426, 3620.855165793339, 3645.2345007548183, 3666.4500324950936, 1132.671525257666, 1138.6299452677747, 1146.2262972493534, 1154.8556430446195, 1157.3229660648615, 852.53110789067, 861.4271509446273, 880.6697488441835, 909.1769110888697, 920.4293338130402, 5266.3943646480375, 5367.812126575629, 5543.972280138573, 5563.360213575491, 5611.382747897728, 414.7076311200657, 430.74370749404056, 449.04353726562215, 449.2407830765995, 450.24583822097856, 3036.544518322225, 3750.006248344191, 3819.388897776342, 3832.9808350958065, 3840.9231815363537, 9304.53477326131, 9735.853962190573, 9749.122578967927, 9798.069183427562, 9986.700398988041, 9907.900920990698, 9927.453990030474, 9934.354266972623, 9941.950290248502, 9953.004699530135, 6584.341565843377, 7537.8115547111765, 7843.37253019757, 7843.843123137505, 7862.410319225966, 5316.840494785162, 5394.2448057272795, 5418.8891277287275, 5515.393306736176, 5537.307836520435, 9182.724518264462, 9254.907450925673, 9280.907190928005, 9320.673776417792, 9327.160555550034, 9219.86170207434, 9322.347435679438, 9467.147956683932, 9582.520873956413, 9586.808263834544, 5616.157576363578, 6415.871682566416, 6510.381513756186, 6605.438537724347, 6778.2205046420295, 7798.610069496389, 7876.921230787848, 7944.563049032358, 7990.720324788605, 7991.800204994922, 6982.51122421446, 7103.396211321889, 7133.6076515790055, 7142.678579463833, 7239.819004524929, 7002.124734408199, 7012.614306213207, 7029.472789540907, 7046.682899269443, 7131.572105673677, 6465.838354041187, 6504.869902602016, 6571.211454625475, 6591.307912669101, 6594.604323740591, 4327.199468098412, 4526.615237704832, 4656.8602941911795, 4708.317293992421, 4817.759112044454, 3814.7520411173186, 3970.8411663533134, 4143.295639741312, 4264.786760662017, 4480.865574032784], "value": 4199.429584395808, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.45254250818232, 282.82486269550014, 289.3605132656828, 300.53717275395894, 306.69637059311276, 4555.88610284743, 5277.551408130314, 5350.705711185893, 5381.838544843656, 5429.348478182618, 4220.36694495825, 4375.8468453604055, 4388.275934470808, 4813.302071199674, 5056.8482945511605, 3626.4560315952554, 3826.846926122959, 3964.2666106770193, 4040.818163182649, 4070.7964601769795, 3415.709664678493, 3416.7095796857175, 4060.573639767826, 4072.287349713803, 4082.6529744971713, 3409.0, 4061.3501839705655, 4063.9593604064025, 4072.36878283865, 4083.5099788025477, 3730.198007428389, 3745.0637340664834, 3970.940435893464, 4102.917941641151, 4116.629503344699, 3467.4625433057918, 3476.1309672581856, 3480.6345333739846, 3481.599616044137, 3505.807180605091, 5135.255387968724, 5163.302954101224, 5190.24741412493, 5205.077247261133, 5232.816851410183, 4043.0, 4366.978165109154, 4562.840300589463, 4575.245084561044, 4596.563326483979, 4795.448523419816, 5243.632945693803, 5475.151351540518, 5502.779888804443, 5547.694876781776, 3753.2359790898236, 3764.247150569897, 3767.2653832502674, 3771.057235691077, 3787.110029143149, 4900.338454308659, 5133.66524703576, 5163.922541161848, 5781.653100813965, 5905.881882362329, 3130.765192610563, 3831.4061320495366, 3851.8459261629223, 3917.4515567820526, 3935.940960885589, 3540.592831824373, 3673.44898265257, 3974.2448934702315, 3993.341098718737, 4003.539592946848, 4567.748773817472, 4591.920898588895, 4617.353570500132, 4626.37543931565, 4672.434734363958, 4734.644901632391, 4763.3807605011125, 5185.170372740344, 5610.587447397658, 5683.886322273532, 1091.9344839309667, 1121.315997241682, 1247.4760600547793, 1287.459267107817, 1296.7406518696298, 825.8554752918216, 858.3133493205536, 868.3964644572088, 869.1873098652779, 874.5190145419994, 5163.586913046947, 5338.039152952506, 5393.946060539344, 5477.4796394342475, 5495.505404513613, 4475.977620111878, 5157.561607263349, 5162.6902385856965, 5167.819126330561, 5900.315356512777, 4729.858104256878, 4779.187538118528, 5149.9742501288, 5201.349831271091, 5424.240606315119, 2496.662950501659, 2649.470105978812, 2695.5148073346986, 2885.6248687670886, 2910.7816913731554, 4877.585405240523, 5885.9987604214775, 5887.558433117533, 5914.733836977346, 5923.571335752321, 4911.87720306988, 5200.761379475715, 5283.260343551906, 5422.0511410503, 5591.776328946797, 5203.14148165556, 5401.864953376118, 5478.863672686616, 5837.270341207349, 5876.207756634097, 4797.760111994457, 5551.306086739158, 5560.165975103605, 5583.812140894329, 5604.6356986797355, 4917.975410123069, 5217.895642087213, 5429.375621803385, 5447.754851031634, 5469.019384435413, 5801.79693710735, 5811.27359080115, 5858.326292476249, 5881.882362352643, 5901.963822481237, 3650.580183278957, 3700.888973330804, 3705.2404257127764, 3751.5123034006497, 3758.9060273493383, 4852.660313778143, 5390.97304513475, 5466.496713403846, 5477.521773673826, 5482.80810171642, 4356.673249506236, 4620.445546534437, 5310.761015754223, 5312.867178320573, 5351.839444816662, 4473.865784026484, 5145.434002259673, 5202.97398513005, 5231.0, 5267.947320526746, 4486.461624605068, 4806.663533552618, 4991.675541089818, 5137.972405518986, 5162.716050617102, 4979.726115063561, 5302.973485132549, 5325.896270972867, 5479.53423958968, 5613.438656134354, 5076.822311219237, 5496.890062198813, 5500.614956952975, 5551.945780061403, 5558.805441809679], "value": 3973.7810940915806, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.4249860123091, 677.2144312597388, 690.3510699942054, 723.4321057969495, 768.581123287808, 14180.574582762521, 14405.855941440583, 14647.414103435876, 14753.11481311122, 14782.556523304318, 13439.462421503129, 13499.325033748322, 13586.932065339684, 13624.795628065587, 13630.591082267523, 837.8910741603598, 847.7753395350237, 861.8060936289316, 861.9094995025495, 863.7754183912165, 822.33390953328, 829.4940086547201, 855.9186877246683, 862.3101518784978, 869.3697069624535, 745.4111252110853, 885.7652722028668, 890.5458216309664, 893.6425429828056, 895.3911340288602, 883.3551507399602, 885.2961895293257, 885.6767279942839, 886.4061079077034, 889.3818795936794, 622.6077571130204, 627.328758228691, 664.7905909638448, 666.9666516674196, 666.9899951500731, 13597.184168949894, 14137.646558836008, 14210.573682789533, 14263.072900261554, 14273.072250303698, 12906.419211135522, 13355.666108347368, 13355.732885342335, 13374.0, 13409.463621455228, 11031.669049928514, 11373.658790236306, 11505.827412588818, 11545.134114941413, 12061.155719099666, 3576.2847430514003, 3583.3549961007006, 3589.5333606631234, 3673.045008297835, 3706.777593344408, 79985.60007199986, 80064.59967700124, 81148.0, 81372.0, 81861.0, 10210.744731381626, 10327.277090603586, 10335.0, 10633.043026127685, 10815.134789216843, 18844.528886777665, 20053.29813456522, 20067.698984515246, 20111.597768044558, 20144.093515791825, 7651.464397492122, 7744.922550774533, 7794.181610930884, 7834.0, 7869.134395216522, 4243.469566304212, 4355.4337936068105, 4357.215701173819, 4358.389825424443, 4433.689641725048, 3283.688049635281, 3373.797572145679, 3403.2002479417315, 3405.557277553902, 3412.1640198151613, 598.7664810723821, 614.913912052313, 631.4348657951108, 637.5505268785495, 640.3852301790333, 590.6279044202125, 653.8071268975664, 661.6096503063216, 678.1794029224595, 686.2005763285816, 8250.257476826995, 8444.0, 8780.473171609723, 9101.544922753836, 9323.487208203615, 580.6283978253921, 604.5375287904757, 658.7628453756644, 665.4543274514948, 673.9730410783513, 4273.423087883159, 4423.955760442419, 4425.380446737459, 4443.488998765119, 4551.362809206714, 89863.55068224747, 102020.0, 103416.0, 106007.0, 113498.0, 68346.65826670834, 73903.26096739071, 93358.53320733353, 100449.46629002853, 102405.0, 68096.6595167021, 87228.56385718031, 89605.0, 90059.54970225238, 91196.08803912009, 7962.920370796218, 8078.515289082674, 8673.653053877773, 8675.089115642766, 8887.0, 49160.0, 56450.71774641101, 56652.0, 57824.71087644535, 58003.0, 71465.6426717863, 72277.0, 74451.67160791393, 75065.24934750583, 76031.61984190265, 11148.219624626523, 11419.828702569303, 12034.097442691658, 12249.816252756038, 12579.182353147016, 13539.458421663418, 13561.86438135606, 14095.718085638026, 14266.785998209827, 14500.85499144995, 12537.811932820832, 12604.32567452353, 12692.982761449673, 12753.61739147827, 13052.282124482865, 13064.281464519543, 13182.198584721038, 13232.536861209812, 13283.468661253442, 13825.0, 12816.295103769382, 12822.23066616006, 13239.20564766117, 13269.734605308033, 13280.53518126861, 9010.729678109667, 10080.596776129167, 10848.915108489404, 10854.837177442187, 11124.388158651353, 10678.786424271315, 10986.890131098906, 11224.494897729459, 11296.548138074386, 11400.201985860911], "value": 7234.273278221187, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 118.0, 119.0, 117.0, 118.0, 118.0, 119.0, 118.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 126.0, 149.0, 116.0, 116.0, 117.0, 121.0, 168.0, 150.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 228.0, 258.0, 259.0, 197.0, 199.0, 197.0, 198.0, 223.0, 213.0], "value": 217.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 61.0, 79.0, 72.0, 50.0, 50.0, 52.0, 91.0, 86.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 85.0, 71.0, 70.0, 69.0, 78.0, 81.0, 102.0, 100.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 129.0, 85.0, 98.0, 86.0, 84.0, 86.0, 90.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 115.0, 160.0, 98.0, 99.0, 99.0, 99.0, 99.0, 151.0, 171.0], "value": 119.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 87.0, 150.0, 146.0, 92.0, 96.0, 114.0, 151.0, 91.0], "value": 112.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 56.0, 59.0, 59.0, 57.0, 57.0, 58.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 81.0, 82.0, 74.0, 72.0, 72.0, 73.0, 74.0, 80.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 84.0, 87.0, 83.0, 99.0, 111.0, 82.0, 78.0, 80.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 142.0, 158.0, 165.0, 163.0, 176.0, 166.0, 149.0, 191.0, 171.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 174.0, 176.0, 176.0, 176.0, 176.0, 174.0, 175.0, 173.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 92.0, 91.0, 91.0, 72.0, 89.0, 89.0, 77.0], "value": 81.0, "unit": "score"}]}]} 22:22:08 INFO - Return code: 0 22:22:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:22:08 ERROR - # TBPL SUCCESS # 22:22:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:22:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:22:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:22:08 INFO - 2016-11-22 22:22:08.464 screenresolution[1956:18559] starting screenresolution argv=screenresolution get 22:22:08 INFO - 2016-11-22 22:22:08.467 screenresolution[1956:18559] Display 0: 1600x1200x32@60 22:22:08 INFO - 2016-11-22 22:22:08.473 screenresolution[1957:18562] starting screenresolution argv=screenresolution list 22:22:08 INFO - Available Modes on Display 0 22:22:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:22:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:22:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:22:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:22:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:22:08 INFO - Intel Iris: 22:22:08 INFO - Chipset Model: Intel Iris 22:22:08 INFO - Type: GPU 22:22:08 INFO - Bus: Built-In 22:22:08 INFO - VRAM (Dynamic, Max): 1536 MB 22:22:08 INFO - Vendor: Intel (0x8086) 22:22:08 INFO - Device ID: 0x0a2e 22:22:08 INFO - Revision ID: 0x0009 22:22:08 INFO - Displays: 22:22:08 INFO - Display: 22:22:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:22:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:22:08 INFO - Display Serial Number: 1600x1200 60 22:22:08 INFO - Main Display: Yes 22:22:08 INFO - Mirror: Off 22:22:08 INFO - Online: Yes 22:22:08 INFO - Rotation: Supported 22:22:08 INFO - Return code: 0 22:22:08 INFO - Running post-action listener: _resource_record_post_action 22:22:08 INFO - [mozharness: 2016-11-23 06:22:08.716657Z] Finished run-tests step (success) 22:22:08 INFO - Running post-run listener: _resource_record_post_run 22:22:08 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 831488; Write bytes: 697658880; Read time: 9; Write time: 1995 22:22:08 INFO - TinderboxPrint: CPU usage
54.8% 22:22:08 INFO - TinderboxPrint: I/O read bytes / time
831,488 / 9 22:22:08 INFO - TinderboxPrint: I/O write bytes / time
697,658,880 / 1,995 22:22:08 INFO - TinderboxPrint: CPU idle
2,194.6 (45.2%) 22:22:08 INFO - TinderboxPrint: CPU system
983.4 (20.3%) 22:22:08 INFO - TinderboxPrint: CPU user
1,675.8 (34.5%) 22:22:08 INFO - TinderboxPrint: Swap in / out
383,135,744 / 0 22:22:08 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 373694976; Write bytes: 366151168; Read time: 27791; Write time: 751 22:22:08 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 831488; Write bytes: 311314944; Read time: 9; Write time: 1185 22:22:09 INFO - Running post-run listener: _upload_blobber_files 22:22:09 INFO - Blob upload gear active. 22:22:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:22:09 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 22:22:09 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'] 22:22:09 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 22:22:09 INFO - (blobuploader) - INFO - Open directory for files ... 22:22:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:22:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:10 INFO - (blobuploader) - INFO - Done attempting. 22:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:11 INFO - (blobuploader) - INFO - Done attempting. 22:22:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:22:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:22:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:13 INFO - (blobuploader) - INFO - Done attempting. 22:22:13 INFO - (blobuploader) - INFO - Iteration through files over. 22:22:13 INFO - Return code: 0 22:22:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:22:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:22:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08207294a7a870d17a0bfaeeec579a77367303abdd095e1376351a25eec79aeaa5d31c98fcc1d7083c4674a6d9e25c7850270dc3263f0ff1ec3528f807dbc2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57548b2ac5e133b3445937df9f575299943be2d2d06e2f7cc02612e126f4ea364795945250a2350b6171da83ab3cc10e0c82601eebe86a4cbd991465128df850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63934753c7a68e9e7e282937595a27dc8bb917f65c04410e6eab2e12ab5f1deaf81b66e168fbec2253cd86c377b687783b64530332f740d81f37e0fb815ffa49"} 22:22:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:22:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:22:13 INFO - Contents: 22:22:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08207294a7a870d17a0bfaeeec579a77367303abdd095e1376351a25eec79aeaa5d31c98fcc1d7083c4674a6d9e25c7850270dc3263f0ff1ec3528f807dbc2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57548b2ac5e133b3445937df9f575299943be2d2d06e2f7cc02612e126f4ea364795945250a2350b6171da83ab3cc10e0c82601eebe86a4cbd991465128df850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63934753c7a68e9e7e282937595a27dc8bb917f65c04410e6eab2e12ab5f1deaf81b66e168fbec2253cd86c377b687783b64530332f740d81f37e0fb815ffa49"} 22:22:13 INFO - Running post-run listener: copy_logs_to_upload_dir 22:22:13 INFO - Copying logs to upload dir... 22:22:13 INFO - mkdir: /builds/slave/test/build/upload/logs 22:22:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.755197 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 22:22:13.606506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:13.610215) ========= 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.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/08207294a7a870d17a0bfaeeec579a77367303abdd095e1376351a25eec79aeaa5d31c98fcc1d7083c4674a6d9e25c7850270dc3263f0ff1ec3528f807dbc2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57548b2ac5e133b3445937df9f575299943be2d2d06e2f7cc02612e126f4ea364795945250a2350b6171da83ab3cc10e0c82601eebe86a4cbd991465128df850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63934753c7a68e9e7e282937595a27dc8bb917f65c04410e6eab2e12ab5f1deaf81b66e168fbec2253cd86c377b687783b64530332f740d81f37e0fb815ffa49"} build_url:https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010731 build_url: 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/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/08207294a7a870d17a0bfaeeec579a77367303abdd095e1376351a25eec79aeaa5d31c98fcc1d7083c4674a6d9e25c7850270dc3263f0ff1ec3528f807dbc2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57548b2ac5e133b3445937df9f575299943be2d2d06e2f7cc02612e126f4ea364795945250a2350b6171da83ab3cc10e0c82601eebe86a4cbd991465128df850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63934753c7a68e9e7e282937595a27dc8bb917f65c04410e6eab2e12ab5f1deaf81b66e168fbec2253cd86c377b687783b64530332f740d81f37e0fb815ffa49"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:13.644289) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:13.644730) ========= 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.xtSjwm98zU/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.NIZAvW29Zb/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:13.694696) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 22:22:13.695030) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 22:22:13.695370) ========= ========= Total master_lag: 0.19 =========