builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0346 starttime: 1493776898.7 results: success (0) buildid: 20170502173721 builduid: a47a5a5587484af0ae9922d3739bb968 revision: a748acbebbde373a88868dc02910fb2bc5e6a023 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:38.703652) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:38.704075) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:38.704376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:38.731507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:38.731808) ========= 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.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-02 19:01:38-- 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% 23.2M=0.001s 2017-05-02 19:01:39 (23.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.257907 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:39.312906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:39.313282) ========= 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.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.037624 ========= master_lag: 0.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:39.508213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 19:01:39.508559) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a748acbebbde373a88868dc02910fb2bc5e6a023 --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 a748acbebbde373a88868dc02910fb2bc5e6a023 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-02 19:01:39,620 truncating revision to first 12 chars 2017-05-02 19:01:39,620 Setting DEBUG logging. 2017-05-02 19:01:39,621 attempt 1/10 2017-05-02 19:01:39,621 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a748acbebbde?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-02 19:01:40,841 unpacking tar archive at: mozilla-central-a748acbebbde/testing/mozharness/ program finished with exit code 0 elapsedTime=1.503612 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-02 19:01:41.036448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:41.036765) ========= script_repo_revision: a748acbebbde373a88868dc02910fb2bc5e6a023 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:41.037141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:41.037422) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 19:01:41.057513) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-02 19:01:41.057939) ========= /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.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:01:41 INFO - MultiFileLogger online at 20170502 19:01:41 in /builds/slave/test 19:01:41 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 19:01:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:41 INFO - {'append_to_log': False, 19:01:41 INFO - 'base_work_dir': '/builds/slave/test', 19:01:41 INFO - 'blob_upload_branch': 'Firefox', 19:01:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:41 INFO - 'branch': 'Firefox', 19:01:41 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:41 INFO - 'config_files': ('talos/mac_config.py',), 19:01:41 INFO - 'default_actions': ('clobber', 19:01:41 INFO - 'read-buildbot-config', 19:01:41 INFO - 'download-and-extract', 19:01:41 INFO - 'populate-webroot', 19:01:41 INFO - 'create-virtualenv', 19:01:41 INFO - 'install', 19:01:41 INFO - 'run-tests'), 19:01:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:41 INFO - 'download_minidump_stackwalk': True, 19:01:41 INFO - 'download_symbols': 'ondemand', 19:01:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:41 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:41 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:41 INFO - 'gecko_profile': False, 19:01:41 INFO - 'gecko_profile_interval': 0, 19:01:41 INFO - 'installer_path': 'installer.exe', 19:01:41 INFO - 'log_level': 'info', 19:01:41 INFO - 'log_name': 'talos', 19:01:41 INFO - 'log_to_console': True, 19:01:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:01:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:01:41 INFO - 'opt_config_files': (), 19:01:41 INFO - 'pip_index': False, 19:01:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:41 INFO - 'cmd': ('bash', 19:01:41 INFO - '-c', 19:01:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:41 INFO - 'enabled': True, 19:01:41 INFO - 'halt_on_failure': False, 19:01:41 INFO - 'name': 'check_screen_resolution'},), 19:01:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:41 INFO - 'cmd': ('bash', 19:01:41 INFO - '-c', 19:01:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:41 INFO - 'enabled': True, 19:01:41 INFO - 'halt_on_failure': False, 19:01:41 INFO - 'name': 'check_screen_resolution'},), 19:01:41 INFO - 'run_cmd_checks_enabled': True, 19:01:41 INFO - 'suite': 'dromaeojs-e10s', 19:01:41 INFO - 'system_bits': '32', 19:01:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:41 INFO - 'title': 't-yosemite-r7-0346', 19:01:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:41 INFO - 'use_talos_json': True, 19:01:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:41 INFO - 'work_dir': 'build'} 19:01:41 INFO - [mozharness: 2017-05-03 02:01:41.277518Z] Running clobber step. 19:01:41 INFO - Running pre-action listener: _resource_record_pre_action 19:01:41 INFO - Running main action method: clobber 19:01:41 INFO - rmtree: /builds/slave/test/build 19:01:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:43 INFO - Running post-action listener: _resource_record_post_action 19:01:43 INFO - [mozharness: 2017-05-03 02:01:43.713363Z] Finished clobber step (success) 19:01:43 INFO - [mozharness: 2017-05-03 02:01:43.713514Z] Running read-buildbot-config step. 19:01:43 INFO - Running pre-action listener: _resource_record_pre_action 19:01:43 INFO - Running main action method: read_buildbot_config 19:01:43 INFO - Using buildbot properties: 19:01:43 INFO - { 19:01:43 INFO - "project": "", 19:01:43 INFO - "product": "firefox", 19:01:43 INFO - "script_repo_revision": "production", 19:01:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:01:43 INFO - "repository": "", 19:01:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:01:43 INFO - "buildid": "20170502173721", 19:01:43 INFO - "pgo_build": "False", 19:01:43 INFO - "basedir": "/builds/slave/test", 19:01:43 INFO - "buildnumber": 60, 19:01:43 INFO - "slavename": "t-yosemite-r7-0346", 19:01:43 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:01:43 INFO - "platform": "yosemite_r7", 19:01:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:01:43 INFO - "branch": "mozilla-central", 19:01:43 INFO - "repo_path": "mozilla-central", 19:01:43 INFO - "revision": "a748acbebbde373a88868dc02910fb2bc5e6a023", 19:01:43 INFO - "stage_platform": "macosx64", 19:01:43 INFO - "builduid": "a47a5a5587484af0ae9922d3739bb968", 19:01:43 INFO - "slavebuilddir": "test" 19:01:43 INFO - } 19:01:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:01:43 INFO - Running post-action listener: _resource_record_post_action 19:01:43 INFO - [mozharness: 2017-05-03 02:01:43.716792Z] Finished read-buildbot-config step (success) 19:01:43 INFO - [mozharness: 2017-05-03 02:01:43.716923Z] Running download-and-extract step. 19:01:43 INFO - Running pre-action listener: _resource_record_pre_action 19:01:43 INFO - Running main action method: download_and_extract 19:01:43 INFO - mkdir: /builds/slave/test/build/tests 19:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:43 INFO - trying https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:01:43 INFO - Downloading https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:01:44 INFO - Downloaded 1325 bytes. 19:01:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:01:44 INFO - Using the following test package requirements: 19:01:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:01:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:01:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:01:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:01:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'jsshell-mac.zip'], 19:01:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:01:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:01:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:01:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:01:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:01:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:01:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:01:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:01:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:01:44 INFO - Fetch https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:01:46 INFO - Content-Length response header: 52699856 19:01:46 INFO - Bytes received: 52699856 19:02:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:02:04 INFO - Content-Length response header: 52699856 19:02:04 INFO - Bytes received: 52699856 19:02:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:02:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:02:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:02:20 INFO - Content-Length response header: 14408811 19:02:20 INFO - Bytes received: 14408811 19:02:20 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')]} 19:02:20 INFO - trying https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:02:22 INFO - Downloaded 71197092 bytes. 19:02:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:02:22 INFO - mkdir: /builds/slave/test/properties 19:02:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:02:22 INFO - Writing to file /builds/slave/test/properties/build_url 19:02:22 INFO - Contents: 19:02:22 INFO - build_url:https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:02:23 INFO - Running post-action listener: _resource_record_post_action 19:02:23 INFO - Running post-action listener: set_extra_try_arguments 19:02:23 INFO - [mozharness: 2017-05-03 02:02:23.661870Z] Finished download-and-extract step (success) 19:02:23 INFO - [mozharness: 2017-05-03 02:02:23.662022Z] Running populate-webroot step. 19:02:23 INFO - Running pre-action listener: _resource_record_pre_action 19:02:23 INFO - Running main action method: populate_webroot 19:02:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:02:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:02:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:02:23 INFO - '--tppagecycles', 19:02:23 INFO - '7'], 19:02:23 INFO - 'tests': ['tsvgm']}}, 19:02:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:02:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:02:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:02:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:02:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['damp', 'tps']}, 19:02:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'tests': ['damp', 'tps']}, 19:02:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['dromaeo_dom']}, 19:02:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:02:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:02:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:02:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:02:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:02:23 INFO - 'bloom_basic_ref']}, 19:02:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tsvgx', 19:02:23 INFO - 'tsvgr_opacity', 19:02:23 INFO - 'tart', 19:02:23 INFO - 'tscrollx', 19:02:23 INFO - 'cart', 19:02:23 INFO - 'tsvg_static']}, 19:02:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:02:23 INFO - 'tsvgr_opacity', 19:02:23 INFO - 'tart', 19:02:23 INFO - 'tscrollx', 19:02:23 INFO - 'cart', 19:02:23 INFO - 'tsvg_static']}, 19:02:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tp5o']}, 19:02:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:02:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s', 19:02:23 INFO - '--xperf_path', 19:02:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:23 INFO - 'tests': ['tp5n']}, 19:02:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--xperf_path', 19:02:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:23 INFO - 'tests': ['tp5n']}}, 19:02:23 INFO - 'talos.zip': {'path': '', 19:02:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:02:23 INFO - Running post-action listener: _resource_record_post_action 19:02:23 INFO - [mozharness: 2017-05-03 02:02:23.681208Z] Finished populate-webroot step (success) 19:02:23 INFO - [mozharness: 2017-05-03 02:02:23.681325Z] Running create-virtualenv step. 19:02:23 INFO - Running pre-action listener: _resource_record_pre_action 19:02:23 INFO - Running main action method: create_virtualenv 19:02:23 INFO - Creating virtualenv /builds/slave/test/build/venv 19:02: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 19:02:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:02:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:02:23 INFO - Using real prefix '/tools/python27' 19:02:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:02:24 INFO - Installing distribute.............................................................................................................................................................................................done. 19:02:27 INFO - Installing pip.................done. 19:02:27 INFO - Return code: 0 19:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:02:27 INFO - Reading from file tmpfile_stdout 19:02:27 INFO - Output received: 19:02: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) 19:02:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02: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 19:02: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 19:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:27 INFO - 'HOME': '/Users/cltbld', 19:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:27 INFO - 'LOGNAME': 'cltbld', 19:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:27 INFO - 'MOZ_NO_REMOTE': '1', 19:02:27 INFO - 'NO_EM_RESTART': '1', 19:02:27 INFO - 'PAGER': '/bin/cat', 19:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:27 INFO - 'PWD': '/builds/slave/test', 19:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:27 INFO - 'SHELL': '/bin/bash', 19:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:27 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:27 INFO - 'USER': 'cltbld', 19:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:27 INFO - 'XPC_FLAGS': '0x0', 19:02:27 INFO - 'XPC_SERVICE_NAME': '0', 19:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02: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 19:02:27 INFO - Cleaning up... 19:02:27 INFO - Return code: 0 19:02:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02: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 19:02: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 19:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:27 INFO - 'HOME': '/Users/cltbld', 19:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:27 INFO - 'LOGNAME': 'cltbld', 19:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:27 INFO - 'MOZ_NO_REMOTE': '1', 19:02:27 INFO - 'NO_EM_RESTART': '1', 19:02:27 INFO - 'PAGER': '/bin/cat', 19:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:27 INFO - 'PWD': '/builds/slave/test', 19:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:27 INFO - 'SHELL': '/bin/bash', 19:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:27 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:27 INFO - 'USER': 'cltbld', 19:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:27 INFO - 'XPC_FLAGS': '0x0', 19:02:27 INFO - 'XPC_SERVICE_NAME': '0', 19:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:28 INFO - Downloading/unpacking psutil>=3.1.1 19:02:28 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 19:02:28 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 19:02:28 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 19:02:28 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 19:02:28 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 19:02:28 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 19:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:02:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:31 INFO - Installing collected packages: psutil 19:02:31 INFO - Running setup.py install for psutil 19:02:31 INFO - building 'psutil._psutil_osx' extension 19:02: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 19:02: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 19:02: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 19:02: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 19:02:32 INFO - building 'psutil._psutil_posix' extension 19:02: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 19:02:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:02:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:02:32 INFO - ^ 19:02:32 INFO - 1 warning generated. 19:02: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 19:02:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:32 INFO - Successfully installed psutil 19:02:32 INFO - Cleaning up... 19:02:32 INFO - Return code: 0 19:02:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02: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 19:02: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 19:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:32 INFO - 'HOME': '/Users/cltbld', 19:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:32 INFO - 'LOGNAME': 'cltbld', 19:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:32 INFO - 'MOZ_NO_REMOTE': '1', 19:02:32 INFO - 'NO_EM_RESTART': '1', 19:02:32 INFO - 'PAGER': '/bin/cat', 19:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:32 INFO - 'PWD': '/builds/slave/test', 19:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:32 INFO - 'SHELL': '/bin/bash', 19:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:32 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:32 INFO - 'USER': 'cltbld', 19:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:32 INFO - 'XPC_FLAGS': '0x0', 19:02:32 INFO - 'XPC_SERVICE_NAME': '0', 19:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:02:35 INFO - Installing collected packages: mozsystemmonitor 19:02:35 INFO - Running setup.py install for mozsystemmonitor 19:02:36 INFO - Successfully installed mozsystemmonitor 19:02:36 INFO - Cleaning up... 19:02:36 INFO - Return code: 0 19:02:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:02:36 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')]} 19:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:36 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')]} 19:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:36 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:36 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 19:02:36 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 19:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:36 INFO - 'HOME': '/Users/cltbld', 19:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:36 INFO - 'LOGNAME': 'cltbld', 19:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:36 INFO - 'MOZ_NO_REMOTE': '1', 19:02:36 INFO - 'NO_EM_RESTART': '1', 19:02:36 INFO - 'PAGER': '/bin/cat', 19:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:36 INFO - 'PWD': '/builds/slave/test', 19:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:36 INFO - 'SHELL': '/bin/bash', 19:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:36 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:36 INFO - 'USER': 'cltbld', 19:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:36 INFO - 'XPC_FLAGS': '0x0', 19:02:36 INFO - 'XPC_SERVICE_NAME': '0', 19:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:37 INFO - Downloading/unpacking jsonschema==2.5.1 19:02:37 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 19:02:37 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 19:02:37 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 19:02:37 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 19:02:37 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 19:02:37 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 19:02:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:40 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:40 INFO - warning: no files found matching '*.txt' 19:02:40 INFO - no previously-included directories found matching 'build' 19:02:40 INFO - no previously-included directories found matching 'dist' 19:02:40 INFO - no previously-included directories found matching '.git*' 19:02:40 INFO - Installing collected packages: jsonschema, functools32 19:02:40 INFO - Running setup.py install for functools32 19:02:40 INFO - warning: no files found matching '*.txt' 19:02:40 INFO - no previously-included directories found matching 'build' 19:02:40 INFO - no previously-included directories found matching 'dist' 19:02:40 INFO - no previously-included directories found matching '.git*' 19:02:40 INFO - Successfully installed jsonschema functools32 19:02:40 INFO - Cleaning up... 19:02:40 INFO - Return code: 0 19:02:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:02:40 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')]} 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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')]} 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:40 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 19:02:40 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 19:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:40 INFO - 'HOME': '/Users/cltbld', 19:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:40 INFO - 'LOGNAME': 'cltbld', 19:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:40 INFO - 'MOZ_NO_REMOTE': '1', 19:02:40 INFO - 'NO_EM_RESTART': '1', 19:02:40 INFO - 'PAGER': '/bin/cat', 19:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:40 INFO - 'PWD': '/builds/slave/test', 19:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:40 INFO - 'SHELL': '/bin/bash', 19:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:40 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:40 INFO - 'USER': 'cltbld', 19:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:40 INFO - 'XPC_FLAGS': '0x0', 19:02:40 INFO - 'XPC_SERVICE_NAME': '0', 19:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:02:41 INFO - Cleaning up... 19:02:41 INFO - Return code: 0 19:02:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:02:41 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')]} 19:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:41 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')]} 19:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:41 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:41 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 19:02:41 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 19:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:41 INFO - 'HOME': '/Users/cltbld', 19:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:41 INFO - 'LOGNAME': 'cltbld', 19:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:41 INFO - 'MOZ_NO_REMOTE': '1', 19:02:41 INFO - 'NO_EM_RESTART': '1', 19:02:41 INFO - 'PAGER': '/bin/cat', 19:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:41 INFO - 'PWD': '/builds/slave/test', 19:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:41 INFO - 'SHELL': '/bin/bash', 19:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:41 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:41 INFO - 'USER': 'cltbld', 19:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:41 INFO - 'XPC_FLAGS': '0x0', 19:02:41 INFO - 'XPC_SERVICE_NAME': '0', 19:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:41 INFO - Downloading/unpacking blobuploader==1.2.4 19:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:02:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:02:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:44 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 19:02:45 INFO - Downloading docopt-0.6.1.tar.gz 19:02:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:02:45 INFO - Installing collected packages: blobuploader, requests, docopt 19:02:45 INFO - Running setup.py install for blobuploader 19:02:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:02:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:02:45 INFO - Running setup.py install for requests 19:02:46 INFO - Running setup.py install for docopt 19:02:46 INFO - Successfully installed blobuploader requests docopt 19:02:46 INFO - Cleaning up... 19:02:46 INFO - Return code: 0 19:02:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:46 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:46 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 19:02:46 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 19:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:46 INFO - 'HOME': '/Users/cltbld', 19:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:46 INFO - 'LOGNAME': 'cltbld', 19:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:46 INFO - 'MOZ_NO_REMOTE': '1', 19:02:46 INFO - 'NO_EM_RESTART': '1', 19:02:46 INFO - 'PAGER': '/bin/cat', 19:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:46 INFO - 'PWD': '/builds/slave/test', 19:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:46 INFO - 'SHELL': '/bin/bash', 19:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:46 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:46 INFO - 'USER': 'cltbld', 19:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:46 INFO - 'XPC_FLAGS': '0x0', 19:02:46 INFO - 'XPC_SERVICE_NAME': '0', 19:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-oRV07Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-RhllaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-XRcNUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-88cfu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-FrlgoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-upV6dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-rKdQQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-299sPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Txw3tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-XdSI_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-5zjDPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NbJmI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-OrR4Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:47 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-RjYFmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:48 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-G2KkMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:48 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-n1X8Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:48 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NwuV_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:48 INFO - Running setup.py install for manifestparser 19:02:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozcrash 19:02:48 INFO - Running setup.py install for mozdebug 19:02:48 INFO - Running setup.py install for mozdevice 19:02:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozfile 19:02:49 INFO - Running setup.py install for mozhttpd 19:02:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozinfo 19:02:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozInstall 19:02:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozleak 19:02:49 INFO - Running setup.py install for mozlog 19:02:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for moznetwork 19:02:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozprocess 19:02:50 INFO - Running setup.py install for mozprofile 19:02:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:50 INFO - Running setup.py install for mozrunner 19:02:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:50 INFO - Running setup.py install for mozscreenshot 19:02:50 INFO - Running setup.py install for moztest 19:02:50 INFO - Running setup.py install for mozversion 19:02:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:50 INFO - Cleaning up... 19:02:50 INFO - Return code: 0 19:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:50 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')]} 19:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:50 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')]} 19:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:50 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:50 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 19:02:50 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 19:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:50 INFO - 'HOME': '/Users/cltbld', 19:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:50 INFO - 'LOGNAME': 'cltbld', 19:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:50 INFO - 'MOZ_NO_REMOTE': '1', 19:02:50 INFO - 'NO_EM_RESTART': '1', 19:02:50 INFO - 'PAGER': '/bin/cat', 19:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:50 INFO - 'PWD': '/builds/slave/test', 19:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:50 INFO - 'SHELL': '/bin/bash', 19:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:50 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:50 INFO - 'USER': 'cltbld', 19:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:50 INFO - 'XPC_FLAGS': '0x0', 19:02:50 INFO - 'XPC_SERVICE_NAME': '0', 19:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-9KmX2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-SnZTZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Dtb_xU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-nbtMtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-fNvzhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-R8Zl_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BszaTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-nMDy41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-1h0lN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-UGI50f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-maj_ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-e8Ts60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-IqGywu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-eQ7XHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-OGSmYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:52 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)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Il6oBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:52 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-oNQrVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:02:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:02:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:55 INFO - Downloading blessings-1.6.tar.gz 19:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:55 INFO - Installing collected packages: blessings 19:02:55 INFO - Running setup.py install for blessings 19:02:55 INFO - Successfully installed blessings 19:02:55 INFO - Cleaning up... 19:02:55 INFO - Return code: 0 19:02:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:56 INFO - Reading from file tmpfile_stdout 19:02:56 INFO - Current package versions: 19:02:56 INFO - blessings == 1.6 19:02:56 INFO - blobuploader == 1.2.4 19:02:56 INFO - docopt == 0.6.1 19:02:56 INFO - functools32 == 3.2.3-2 19:02:56 INFO - jsonschema == 2.5.1 19:02:56 INFO - manifestparser == 1.1 19:02:56 INFO - mozInstall == 1.12 19:02:56 INFO - mozcrash == 1.0 19:02:56 INFO - mozdebug == 0.1 19:02:56 INFO - mozdevice == 0.50 19:02:56 INFO - mozfile == 1.2 19:02:56 INFO - mozhttpd == 0.7 19:02:56 INFO - mozinfo == 0.9 19:02:56 INFO - mozleak == 0.1 19:02:56 INFO - mozlog == 3.4 19:02:56 INFO - moznetwork == 0.27 19:02:56 INFO - mozprocess == 0.25 19:02:56 INFO - mozprofile == 0.28 19:02:56 INFO - mozrunner == 6.13 19:02:56 INFO - mozscreenshot == 0.1 19:02:56 INFO - mozsystemmonitor == 0.3 19:02:56 INFO - moztest == 0.8 19:02:56 INFO - mozversion == 1.4 19:02:56 INFO - psutil == 3.1.1 19:02:56 INFO - requests == 1.2.3 19:02:56 INFO - wsgiref == 0.1.2 19:02:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:56 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')]} 19:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:56 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')]} 19:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:56 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:56 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 19:02:56 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 19:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:56 INFO - 'HOME': '/Users/cltbld', 19:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:56 INFO - 'LOGNAME': 'cltbld', 19:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:56 INFO - 'MOZ_NO_REMOTE': '1', 19:02:56 INFO - 'NO_EM_RESTART': '1', 19:02:56 INFO - 'PAGER': '/bin/cat', 19:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:56 INFO - 'PWD': '/builds/slave/test', 19:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:56 INFO - 'SHELL': '/bin/bash', 19:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:56 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:56 INFO - 'USER': 'cltbld', 19:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:56 INFO - 'XPC_FLAGS': '0x0', 19:02:56 INFO - 'XPC_SERVICE_NAME': '0', 19:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 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)) 19:02:56 INFO - Cleaning up... 19:02:56 INFO - Return code: 0 19:02:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02:56 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')]} 19:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:56 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')]} 19:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:56 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 0x107845b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107848768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0b563f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, '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.rMrFnKWwCx/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.99nHxsZA2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 19:02:56 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 19:02:56 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 19:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:56 INFO - 'HOME': '/Users/cltbld', 19:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:56 INFO - 'LOGNAME': 'cltbld', 19:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:56 INFO - 'MOZ_NO_REMOTE': '1', 19:02:56 INFO - 'NO_EM_RESTART': '1', 19:02:56 INFO - 'PAGER': '/bin/cat', 19:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:56 INFO - 'PWD': '/builds/slave/test', 19:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:56 INFO - 'SHELL': '/bin/bash', 19:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:02:56 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:56 INFO - 'USER': 'cltbld', 19:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:56 INFO - 'XPC_FLAGS': '0x0', 19:02:56 INFO - 'XPC_SERVICE_NAME': '0', 19:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:02:56 INFO - Cleaning up... 19:02:56 INFO - Return code: 0 19:02:56 INFO - Running post-action listener: _resource_record_post_action 19:02:56 INFO - Running post-action listener: _start_resource_monitoring 19:02:56 INFO - Starting resource monitoring. 19:02:56 INFO - [mozharness: 2017-05-03 02:02:56.745240Z] Finished create-virtualenv step (success) 19:02:56 INFO - [mozharness: 2017-05-03 02:02:56.746186Z] Running install step. 19:02:56 INFO - Running pre-action listener: _resource_record_pre_action 19:02:56 INFO - Running main action method: install 19:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:57 INFO - Reading from file tmpfile_stdout 19:02:57 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:57 INFO - Reading from file tmpfile_stdout 19:02:57 INFO - Output received: 19:02:57 INFO - Usage: mozinstall [options] installer 19:02:57 INFO - Options: 19:02:57 INFO - -h, --help show this help message and exit 19:02:57 INFO - -d DEST, --destination=DEST 19:02:57 INFO - Directory to install application into. [default: 19:02:57 INFO - "/builds/slave/test"] 19:02:57 INFO - --app=APP Application being installed. [default: firefox] 19:02:57 INFO - mkdir: /builds/slave/test/build/application 19:02:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:03:16 INFO - Reading from file tmpfile_stdout 19:03:16 INFO - Output received: 19:03:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:03:16 INFO - Running post-action listener: _resource_record_post_action 19:03:16 INFO - [mozharness: 2017-05-03 02:03:16.607333Z] Finished install step (success) 19:03:16 INFO - [mozharness: 2017-05-03 02:03:16.607462Z] Running run-tests step. 19:03:16 INFO - Running pre-action listener: _resource_record_pre_action 19:03:16 INFO - Running main action method: run_tests 19:03:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:03:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:03:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:03:16 INFO - 2017-05-02 19:03:16.622 screenresolution[1621:7300] starting screenresolution argv=screenresolution get 19:03:16 INFO - 2017-05-02 19:03:16.624 screenresolution[1621:7300] Display 0: 1600x1200x32@60 19:03:16 INFO - 2017-05-02 19:03:16.630 screenresolution[1622:7303] starting screenresolution argv=screenresolution list 19:03:16 INFO - Available Modes on Display 0 19:03:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:03:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:03:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:03:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:03:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:03:16 INFO - Intel Iris: 19:03:16 INFO - Chipset Model: Intel Iris 19:03:16 INFO - Type: GPU 19:03:16 INFO - Bus: Built-In 19:03:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:03:16 INFO - Vendor: Intel (0x8086) 19:03:16 INFO - Device ID: 0x0a2e 19:03:16 INFO - Revision ID: 0x0009 19:03:16 INFO - Displays: 19:03:16 INFO - Display: 19:03:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:03:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:03:16 INFO - Display Serial Number: 1600x1200 60 19:03:16 INFO - Main Display: Yes 19:03:16 INFO - Mirror: Off 19:03:16 INFO - Online: Yes 19:03:16 INFO - Rotation: Supported 19:03:16 INFO - Return code: 0 19:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:03:16 INFO - Python 2.7.3 19:03:16 INFO - Return code: 0 19:03:16 INFO - grabbing minidump binary from tooltool 19:03:16 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')]} 19:03:16 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 0x107623d30>, '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 0x107549db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107618be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:03:16 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 19:03:16 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 19:03:16 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 19:03:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:03:16 INFO - Return code: 0 19:03:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:03:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:03:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:03:16 INFO - ENV: RUST_BACKTRACE is now 1 19:03:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:03:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:03:16 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/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 19:03:16 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/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --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 19:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMrFnKWwCx/Render', 19:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:16 INFO - 'HOME': '/Users/cltbld', 19:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:16 INFO - 'LOGNAME': 'cltbld', 19:03:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:03:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:16 INFO - 'MOZ_NO_REMOTE': '1', 19:03:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:03:16 INFO - 'NO_EM_RESTART': '1', 19:03:16 INFO - 'PAGER': '/bin/cat', 19:03:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:16 INFO - 'PWD': '/builds/slave/test', 19:03:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:16 INFO - 'RUST_BACKTRACE': '1', 19:03:16 INFO - 'SHELL': '/bin/bash', 19:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.99nHxsZA2q/Listeners', 19:03:16 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 19:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:16 INFO - 'USER': 'cltbld', 19:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:16 INFO - 'XPC_FLAGS': '0x0', 19:03:16 INFO - 'XPC_SERVICE_NAME': '0', 19:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:16 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/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 19:03:17 INFO - mozversion application_buildid: 20170502173721 19:03:17 INFO - mozversion application_changeset: a748acbebbde373a88868dc02910fb2bc5e6a023 19:03:17 INFO - mozversion application_display_name: Nightly 19:03:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:03:17 INFO - mozversion application_name: Firefox 19:03:17 INFO - mozversion application_remotingname: firefox 19:03:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:03:17 INFO - mozversion application_vendor: Mozilla 19:03:17 INFO - mozversion application_version: 55.0a1 19:03:17 INFO - mozversion platform_buildid: 20170502173721 19:03:17 INFO - mozversion platform_changeset: a748acbebbde373a88868dc02910fb2bc5e6a023 19:03:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:03:17 INFO - mozversion platform_version: 55.0a1 19:03:17 INFO - using testdate: 1493776997 19:03:17 INFO - actual date: 1493776997 19:03:17 INFO - starting webserver on 'localhost:49229' 19:03:17 INFO - SUITE-START | Running 2 tests 19:03:17 INFO - TEST-START | dromaeo_css 19:03:17 INFO - operating with platform_type : mac_ 19:03:17 INFO - Initialising browser for dromaeo_css test... 19:03:17 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpbxRJ2c/profile http://localhost:49229/getInfo.html) 19:03:20 INFO - PID 1632 | 2017-05-02 19:03:20.262 plugin-container[1634:7490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 19:03:20 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:20 INFO - PID 1632 | 2017-05-02 19:03:20.266 plugin-container[1634:7490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 19:03:20 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:20 INFO - PID 1632 | 2017-05-02 19:03:20.266 plugin-container[1634:7490] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:20 INFO - PID 1632 | __metrics Screen width/height:1600/1200 19:03:20 INFO - PID 1632 | colorDepth:24 19:03:20 INFO - PID 1632 | Browser inner width/height: 1024/691 19:03:20 INFO - PID 1632 | __metrics 19:03:21 INFO - TEST-INFO | 1632: exit 0 19:03:21 INFO - Browser initialized. 19:03:21 INFO - Running cycle 1/1 for dromaeo_css test... 19:03:21 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpbxRJ2c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:23 INFO - PID 1635 | 2017-05-02 19:03:23.580 plugin-container[1636:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 19:03:23 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:23 INFO - PID 1635 | 2017-05-02 19:03:23.582 plugin-container[1636:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 19:03:23 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:23 INFO - PID 1635 | 2017-05-02 19:03:23.582 plugin-container[1636:7622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:33 INFO - PID 1635 | RSS: Main: 235446272 19:03:33 INFO - PID 1635 | 19:06:37 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 19:06:39 INFO - PID 1635 | RSS: Main: 251781120 19:06:39 INFO - PID 1635 | 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 19:08:21 INFO - PID 1635 | 19:08:21 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:08:21 INFO - PID 1635 | Full stack: 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 19:08:21 INFO - PID 1635 | 19:08:21 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:08:21 INFO - PID 1635 | Full stack: 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 19:08:21 INFO - PID 1635 | 19:08:21 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:08:21 INFO - PID 1635 | Full stack: 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | ************************* 19:08:21 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 19:08:21 INFO - PID 1635 | 19:08:21 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:08:21 INFO - PID 1635 | Full stack: 19:08:21 INFO - PID 1635 | ************************* 19:09:40 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 19:09:41 INFO - PID 1635 | RSS: Main: 255442944 19:09:41 INFO - PID 1635 | 19:12:43 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 19:12:43 INFO - PID 1635 | RSS: Main: 266428416 19:12:43 INFO - PID 1635 | 19:15:45 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 19:15:46 INFO - PID 1635 | RSS: Main: 259035136 19:15:46 INFO - PID 1635 | 19:18:49 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 19:18:49 INFO - PID 1635 | RSS: Main: 268337152 19:18:49 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 19:21:51 INFO - PID 1635 | RSS: Main: 262991872 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | __start_tp_report 19:21:51 INFO - PID 1635 | _x_x_mozilla_page_load 19:21:51 INFO - PID 1635 | _x_x_mozilla_page_load_details 19:21:51 INFO - PID 1635 | |i|pagename|runs| 19:21:51 INFO - PID 1635 | |0;dojo.html;3622.6739593436587;3630.0198946284504;3640.5631324241094;3644.6902013328868;3656.9634303656953;6108.402780883657;6205.472534834539;6237.5009999200065;6263.029230469273;6284.780032698856;5088.694678319294;6209.751609935597;6213.068039794033;6281.685915704219;6296.779612713556;5426.240326354314;5438.619296649236;5449.5640348772;5455.1271796512565;5471.644343117705;5509.586780991422;5566.832995010156;5589.804356847511;5651.6043876928425;5654.971725141369;5375.489328513785;5488.368837583649;5513.889722205534;5564.527015203713;5571.4428557144365;5437.510624043815;5467.371252305996;5495.505404513573;5524.668519888779;5571.498565129158;5380.623356365056;5425.782968681248;5448.509634132907;5466.590005749585;5467.972660136714;6032.999999999956;6288.559800813945;6296.590721603127;6307.936920630781;6323.177986861724;5835.562332825013;6414.134091897581;6505.707243174069;6720.697568609425;6750.35871592198;6856.657167141673;6868.965655171743;6888;6904.171499420051;6914.688839002257;6226.346233645492;6386.840328991766;6391.520635952275;6401.487880969511;6412.294647588761;5549.5837812163445;6722.327767223239;6831.829204269834;6849.657517124124;7022.719091236293;6422;6498.155239818888;6532.608043517404;6562.212534495891;6644.468442524587;5571.247881536022;6681.5656982296;6819.147606549182;6827.829304267334;6868.347506986828;6690.933090669128;6728.899066514006;6738.764143255061;6740.39336459721;6750.392464678104;6343.3339499351905;6595.241547222035;6685.732570697118;6687.929931210982;6773.153355830521;5277.1292736698015;5331.546818520469;5360.544353729977;5389.622726409114;5426.430224826337;4761.4524329701835;4772.451168115642;4781.832635857728;4782.952170478251;4843.975780121077;6180.845478863064;6401.359864013654;6416.967915160488;6425.228972523327;6437.485001199986;6704;6795.456363490909;6803.48973826965;6858.348456896587;6884.414824739853;4756.738379389098;6342.270638876496;6375.234971803413;6375.4262116409745;6378.266499352541;6057.454829065317;6061.7575296987625;6065.059915713072;6086.122308988576;6093.634381937098;6787.321267873173;6885.896711549431;6942.895856562054;7020.368166865007;7043.471739619446;6821.420179284816;6915.515913886181;6921.757738479129;6927.272636373108;6939.930600693929;6938.375546200866;7013.368796808108;7063.57618542889;7081.752138675121;7112.288771122846;6397.616143031433;6408.263049749338;6494.6752662367635;6570.5400621957915;6761.22245941723;5764.740586673526;6699.39705426514;6876.346747059021;6917.342852429011;6925.792226233221;5203.92194117081;6288.339724329063;6499.545031847725;6510.47916166715;6570.967145164434;6403.679816009274;6492.220933488012;6516.315786842503;6525.673716314261;6539.574927629689;6290.370962903856;6358.364163583789;6432.710528026157;6450.903236451551;6453.386928241809;6535.477161827138;6558.737650493927;6563.474922006323;6565;6567.014947757874;6172.567920245539;6178.567500275118;6297.055441683601;6387.840303992437;6390 19:21:51 INFO - PID 1635 | |1;ext.html;712.8111050571598;753.703629756818;832.2717622080679;1206.722453835618;1255.7927941889589;37900;38906;38910;38938;39045.02387440315;38410.03974900622;38834.805825970834;38838;38856.02859928503;38875.63935262266;7830.882536761939;7973;8276.172382761735;8481.787955301104;8503;8449.32405407569;8473.872891906627;8542.402031857773;8576;8605.61274742635;8539.74380768578;8575.82848343036;8575.914240857604;8612.39713220075;8633.69782057628;8416.368772342068;8543.487390756543;8547.74356769298;8560.957195214047;8562.914370856273;8417.95791021047;8460.66157353705;8463.4075614707;8472.576371181478;8485.193906578865;38225.80887095575;38285.80857095725;38473.80763096194;38498.80750596257;38516.80741596274;3564.3940530109944;3602.3335682898746;3602.963970360289;3606.278744251134;3616.439451884962;31108.37783244345;31164;31177;31258.37483250345;31271;3052.771042171824;3080.476319025771;3087.058447173611;3090.227443139215;3090.7990980586414;242436;289645;293370;293722;295600.99999999785;48678.51321486811;48971.02057958821;49057.264141037915;49171.508284916694;49218.75390623024;45107.54892451099;49443.75278123586;52163;54449.1832622511;54658.726706366215;14467.92766036163;14546.345414456375;14726.263686815617;14737.042092263968;14746.188959607325;11673.824892626617;11768.646940591796;11776.234544754563;11780.469878855472;11780.764384712258;11284.492197851116;11310.830337544943;13936.86063139376;14022.789658155136;14083.929580352238;6225.159603453566;6254.9687251563455;6272.153259310026;6297.590656607397;6404.903926441107;822.3708862720024;1042.1142029275206;1042.47876061969;1050.0969166516788;1075.483368916723;20196.899015504827;24550.895209715607;24827.62758558623;25018.749062546798;25315.87342063278;1017.4759998600726;1125.786100640892;1132.694965744722;1149.8505194324684;1161.6456980620992;12802.80795788064;12806.167599106027;12816.35918204086;12829.358532073358;12840.743185136433;326134.73865261517;326580;329606;329841.0523842144;330074;53734.46265537295;54381.36855894329;54435;54661;54723.45276547263;1315.2700251360395;1465.1721777196062;1528.9221099125311;1549.1944189021647;1558.0340189082842;16799.91600042041;24159.51680966406;24206.757932421155;24377.02491900304;24423.511529768948;38060.238795223384;38082;38191.61808381881;38248.42627360648;38292.425613616375;271613.64193178905;271927;272921;273218.9017164704;274637;35078;35302.29395412129;35380.823095884356;35475.46786798148;35553.28893422169;42851.215085963;43210.78394608007;44200.05548289907;44741.55258447374;44756.65730028104;29309.26726831846;29972.85013574918;30142.84928575343;30865;31227.219319517193;30246;30488.84755576208;30535.694643053284;30821.845890770404;30878.53682194724;35698.46452303269;35801.28397432089;35918.640813591526;36005;36777.63222367849;24346.51306973815;29976;30642;30836.69163308428;30984.760609575365;19573.62984591065;20263.986800660205;22910;23825.404364891016;24085.759142409053 19:21:51 INFO - PID 1635 | |2;jquery.html;431.8056874406518;436.64849795914284;440.83909373078814;456.52293353445646;459.4647235970095;16937.491875243748;22059.66910496344;22151.889240553795;22420.88789556054;22691.54616907661;22879.77120228797;22883.656745148837;23329.3001209964;23519.647205291934;23737;6098.054801505763;6228.719707613168;6248;6295.370462953714;6304.117423560682;5987.251593550806;6237.844053898666;6272.153259310003;6273.184486016811;6282.811515654515;5983;6158.107074474198;6216.222972128484;6233.657148856811;6251.062340648894;6143.232095988002;6166.7224974876235;6217.378262173746;6218.906716399258;6219.564630475868;5393.083175860113;5536.390996990328;5543.334799824047;5584.441555844423;5588.133839254922;26303.73696263051;27033.86483067572;28549.57175642367;28748.850045998133;28867.566986495218;2195.8133558647373;2276.46503071778;2276.84062115652;2277.4420267034684;2280.372897453197;14424.927875360661;17365.565860853454;17886.642267154715;17909.910450447664;18474;2150.559135377244;2189.4416923684357;2189.4745261137214;2196.461866842618;2212.2478357358577;126203;148577;151071.97856042816;155638.22180889023;165853;12243.877561224452;12761.744765104646;12864.678383040311;12868.420921058574;12914.612561623166;16362.918185409235;16714.498565043068;16733;16747.246373913204;16754.497365079067;8947;9123.270138388913;9198.862017069749;9229.030951750105;9331.620436788575;7310.378617817439;8669.445527333763;8698.652053917898;8771;8979.416337938013;7947.205279472007;8070.757877263692;8096.311813495919;8099;8107.473014254055;2145.742510898702;2149.430400943751;2151.1072904744356;2151.354593621907;2306.619407797728;821.9219174178444;858.2447446247262;933.748776639293;934.1834369376336;1014.8680671512656;20833.479163020744;21306.467338316666;21376.251831185833;21499.355019349445;21537;859.7936495241097;1017.6133069433716;1050.5430137890041;1060.5810704771727;1062.9946850265853;10682.252242342962;10788.78422431547;13226.669333266746;13749.175049497062;14300.85699143016;525648;563792;565669.6905282283;566706;567415;104277.95722042835;126100.36949815376;131805;132621;135762.53306068262;1215.9817602735966;1244.794608889523;1248.9687757806128;1261.394530625305;1264.8292480515013;20221.79778202258;20330.390088297376;22349;23012.4246893829;23028.654570181126;55712;56331;56658.716706416206;57558;57965;80880;80976.59511702404;81230.7815382758;81453;81484.18515815004;8683.913160868564;8835.116488351114;8954.910450895408;9191.356605037583;9222.677206297747;13712.52006179779;14452.205128718022;14563.563093107225;14756.114633121617;15000.699986000001;11969.042476602026;12173.330466824409;12202.14584979043;12346.567870124503;12392.442340094898;8293.004839419309;9037.412568183046;10506.264561480622;10670.46647667598;10850.565977360817;12318.50725970987;12379.814302785646;12553.686157846127;12591.559295424617;13404.195748255135;6898.344657257552;7454.739084132029;8769.429987050822;8859.3798434109;9016.864747028669;9387.530623468663;9611.663591774255;12131.45408456604;12241.571544995883;12290.078244131548 19:21:51 INFO - PID 1635 | |3;mootools.html;1336.138190866891;1475.38771409865;1488.107135718569;1489.3595753825864;1495.2673190136834;8745.7813554661;11130.666080017592;11731.941340293308;11766.882331176696;11929.642110736691;11535.480903359348;11707.882921170785;11708.531658733662;11709.70725731857;11739.119566032561;2475.443025319307;2534.2650631316988;2537.4671319023028;2545.210984594772;2549.0441084593276;2477.7150627677865;2504.161106029482;2528.506941146477;2532.5821239495463;2541.034406925375;2463.9014439422403;2477.0463371601973;2508.7742103210708;2520.143151328548;2521.9747802521924;2472.11004038546;2488.042103790036;2504.686914135733;2507.122507122511;2507.5361058204107;2424.9151279705125;2483.4039830440743;2485.8757062147;2487.589547724623;2521.2940376694555;9689.224862011093;11607.593734219347;11808.46861891217;11813.881861181364;11908.583199587973;6519.185101862267;6756.189257289157;6763.763268285636;6766.018927255521;6781.932180678229;3409.2840503494294;3426.6573342665783;3648.7810731356203;3655.0131464504434;4318.568143185687;1057.973550661232;1147.9668298531305;1280.5582074184392;1288.072587736828;1292.9030322725832;14797.40810367584;15929.203539823078;16289.91855040729;16309.755353669705;16499.422520211854;5486.341639003265;5564.19319198722;5571.804986825442;5721.685307308138;5731.484166425042;11687.065034797195;11812.232204906653;11843.052555795515;11854.407279635983;11886.227395219283;6517.739290428424;6533.869322613521;6540.4767618590395;6541.084248205255;6543;5970.880582388328;6078.45293923549;6186.041163619646;6229.906551401733;6231.844203894848;3905.8477749063527;4201.432806571073;4207.474065741782;4383.531516941796;4434.889127771767;1240.344140572346;1251.5869762978195;1258.4399942025893;1266.3858028855998;1266.4364357860663;770.7803276066294;796.6773456750026;809.8218391953761;810.3152835853695;829.5147338806745;6124.601900876429;6142.754289828356;6306.085617585424;6354.523410744213;6362;578.4591407034436;583.186454895427;593.3828818029288;601.8555546668855;606.4693393280879;4469.955300447019;4473.865784026484;4515.435570553681;4658.138244424792;4666.4633567139535;14965.92517037408;14980.250987450583;15193.696126077417;15227.771583426258;15233.466828660943;10620;14803.555893323217;14820.555383338517;14882.330295136744;15076.924615376853;8241.175882411711;8318.875216871751;8455.873161902577;8534.87197692035;8551.358648101294;6702.396784289437;6803.353681400259;7011.404030657452;7062.505624606229;7066.258042905421;12138.089643276966;13113.27876966776;13476.393562289524;13556.864431355954;13569.457221711022;13266.137701049402;13595.252261125734;13697.315134243048;13938.442462301395;13997.370118344495;8330.208630180123;9003.54982250898;9187.724368268962;9339.57971891253;9445.622175113192;10351.120154786928;10509.106725928381;10548;10655.30740501865;10661.946690266499;7560.281773231534;7719.92280077192;8849;9040.321975851968;9156.03861594523;8816.911830881609;8848.64605415802;8936.329775267011;8970;9142;7959.363250939795;8083.474574152661;8184.8772268417215;8194.22154895284;8206.876896846432;5997.83042306746;6001.639901605918;6096.237970253718;6114.908276375769;6210.3168651449205;5326.573874090141;5959.642421454727;6040.305364883095;6111.358307377662;6230.221222347207 19:21:51 INFO - PID 1635 | |4;prototype.html;329.4695540180309;395.28256214969826;401.530209654704;403.12119579317095;407.5170922456889;5155.329807125068;6168.414000669941;6269.780557680482;6297.779577714781;6524.347565243474;6246.687665616723;6309.842253943653;6321.620702757827;6356.3008069112475;6359.872802543946;4418.779061046935;4421.358902959069;4532.13889361022;4546.499885012646;4549.24937385331;4536.319552067183;4537.863864084083;4540.18276710192;4550.451747021679;4561.635069194473;4504.527024662396;4511.661625378109;4513.706609070417;4515.571020753023;4526.388937493429;3987.920241595181;4322.956770432303;4482.417285752831;4522.8643140705835;4530.59224669778;3512.947305790415;3763.510743603314;3853.8843834685003;3859.536855577349;3865.246276975991;4933.753312334369;5065.97361055577;5081.992612917914;5960.910586341209;6101;5286.894262114736;5413.996821176151;5460.453954604547;5477.726113694339;5486.807961721321;5563.833085007415;6731.2932142125355;6740.359665831738;6781.457483401366;6785;4245.405643209953;4376.606105450493;4383.693141480097;4383.846565370197;4400.361947517624;5938.4761476372805;6569.704363303663;6989.860202795967;7186.269217697241;7277.272272772733;4402.493713222956;4453.22068637987;4457.04173602677;4458.799354029012;4484.820607175677;4530.116627257653;4532.27483602622;4536.637069034469;4545.499995000548;4547.408836851254;5339.866503337447;5377.677339359651;5444.794858082483;5464.153056276284;5484.628842789302;6849.452043836481;6857.457072158724;6858.485613579001;6868.111269400954;6880.208775990725;1263.759885621729;1444.6460617148866;1539.0915265071455;1542.1749364090158;1545.2196640696377;848.9533075680897;870.1950695606539;923.4736200365727;945.8507912885876;995.7759504111517;5966.433188847052;6133.877322453526;6434.549581529342;6434.613923164625;6454.709538070798;5911.832158328368;6882.8623427532175;7088;7105.609191494517;7111.608861512666;6350.4602108820345;6368.426841584187;6380.649064301415;6405.48756099511;6421.614703117828;3243.3351163011525;3545.840437180333;3635.6727894489095;3681.742278040565;3686.4654625079215;6664;6937.7224911002995;7195.92804071963;7202.243764404662;7246.420286377077;5938.960681880653;5992.123780585256;6456.51576131792;7095.410175285983;7251.274872512707;5533.861653458615;6990.965045174843;7181.455986962724;7208.8918666219;7254.238304978006;6402.391772781578;6882.5870447773295;6946.479014074066;7004.264552221944;7069.717211311696;6302.65335406557;6496.577722448026;6837.9059350503685;6862.725490980305;6889.483288753464;5612.691301978431;7031.897783238864;7202.927970720435;7245.529040612343;7269.818254543467;4391.099824536026;4392.956070439254;4402.691811573287;4406.867793966058;4437.42313499243;5534.418886016911;6365.363463653783;6888.967754512531;6970.024196612377;6973.919042548413;5106.923396148986;6208.906866397098;6643.3356664335115;6654.5341826073645;6707.189058954153;6035.879282414415;6280.806646737105;6478.294964711501;6499.28507864144;6556.541042127005;5392.649477783932;5465.07093794048;5756.625819321731;6504.60972341661;6582.703778329891;5168.483151684802;5541.612087153861;5758.884822303614;6675.198976122896;6685.264620891795;5799.362070172193;6010.639361638316;6083.756649733961;6099.66451845135;6460.386263305167 19:21:51 INFO - PID 1635 | |5;yui.html;725.5247812682693;757.299861810236;804.4730701390591;821.8438496685629;841.9410641255115;13714.794278085838;15175.54473365798;16577.668446631076;18601.069946502656;19152.616947661038;17650.64698706028;17984.100794960264;17988.370407035756;18114.456566303033;18250.817491825077;970.5341436110671;998.4009594243432;1001.9549120289604;1002.6190047781832;1006.2553710254396;994.9502524873727;996.8704068471088;997;997.0727223681973;1004.3823048824956;1046.408779039844;1047.1988928469727;1059.5602824827727;1063.1972860490318;1069.7967386196638;1037.6783197208813;1058.016045078077;1059.1526778577108;1064.3347907557777;1064.542246833861;714.8641758065951;738.9039424874732;771.9382449404034;789.8973133492667;792.8295416485425;15620.453284135114;15983.280752366172;16020.599485012852;16020.759688604563;16066.357345706158;16611.750823737653;17044.573885652833;17215.96704197752;17435.476935691822;17462.563435914075;13642.795358069638;13834.93082534591;14087.506937257147;14153.504627338096;14177.645558861008;3432.090496018557;3481.1471189558465;3484.4424892017155;3489.4068008438626;3500.4399296112756;105191.99999999923;111744.88255117426;112060;125252.74747252502;125446;12735.29955852437;13169.473221071241;13219.140755850838;13299.401526931311;13326.334208223972;23918.88040559786;24269.51460970771;24608.261752147464;24664.383390415383;24739;7795.80510487231;8009.559474228857;8055.7583272501915;8111.269985701315;8111.351091912633;3234.5956755405573;3963.3064213762477;4042.514898212233;4043.8989025274605;4048.291548978917;4278.144371125787;4314.331278651814;4373.409589705413;4404.515503294635;4422.049259409242;918.1919910478736;950.4012472142506;955.4649396338073;960.2030314838668;964.9903500965041;637.7002808679913;654.4862283107833;656.4059526128862;661.408039804382;688.7451642892162;10924.508397122148;11883.227590206609;11962.282263064244;12827.935860320826;13320.533781317607;641.6791604197902;649.5128653509868;671.8622682350205;685.4071228387409;694.8193469697813;5320.361556613231;5374.35507739066;5415.702136382537;5427.810026649048;5472;84299.15700842913;91444.5427772857;98565;99835;101423.49288253511;78683.49215554971;79310.60344698354;80492;86312;90089;74717.62641186759;76586.23413765902;82189;84911.5754421224;89372;11059.557617695202;11100.5559777608;12275.324857132944;12284.692882678004;12534.111696438214;39309;41119.7944010278;41329;42864.142717146104;43852.12295754131;60992.08511872414;67054;69286;71843;74194.88707669497;16304.592385190464;16765.32938682439;17059.829401705825;17359.479215623553;17501.737473938156;18269.634607308046;18870.52823679419;18947.715784263524;19083.522911927314;19128.426147215607;13004.95960323153;16633.750493742362;16674.999500030037;17381.13094345303;17445.30218791234;13609.79585306241;15544.766828497355;17224.741628875327;17463.82536174622;17612;13970;16323;16526.256318465938;16944.830551694326;17133.143342833057;11998.64004079879;12006.339651319262;12137.757244855231;12150.696232594255;12427.31649759272;12815.231086134861;13488.6627834301;13517.266001547145;13523.323833808074;13540 19:21:51 INFO - PID 1635 | __end_tp_report 19:21:51 INFO - PID 1635 | __start_cc_report 19:21:51 INFO - PID 1635 | _x_x_mozilla_cycle_collect,420 19:21:51 INFO - PID 1635 | __end_cc_report 19:21:51 INFO - PID 1635 | __startTimestamp1493778111873__endTimestamp 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | ------- Summary: start ------- 19:21:51 INFO - PID 1635 | Number of tests: 6 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6174.99 Median:6391.52 stddev:736.13 (11.5%) stddev-sans-first:710.64 19:21:51 INFO - PID 1635 | Values: 3622.7 3630.0 3640.6 3644.7 3657.0 6108.4 6205.5 6237.5 6263.0 6284.8 5088.7 6209.8 6213.1 6281.7 6296.8 5426.2 5438.6 5449.6 5455.1 5471.6 5509.6 5566.8 5589.8 5651.6 5655.0 5375.5 5488.4 5513.9 5564.5 5571.4 5437.5 5467.4 5495.5 5524.7 5571.5 5380.6 5425.8 5448.5 5466.6 5468.0 6033.0 6288.6 6296.6 6307.9 6323.2 5835.6 6414.1 6505.7 6720.7 6750.4 6856.7 6869.0 6888.0 6904.2 6914.7 6226.3 6386.8 6391.5 6401.5 6412.3 5549.6 6722.3 6831.8 6849.7 7022.7 6422.0 6498.2 6532.6 6562.2 6644.5 5571.2 6681.6 6819.1 6827.8 6868.3 6690.9 6728.9 6738.8 6740.4 6750.4 6343.3 6595.2 6685.7 6687.9 6773.2 5277.1 5331.5 5360.5 5389.6 5426.4 4761.5 4772.5 4781.8 4783.0 4844.0 6180.8 6401.4 6417.0 6425.2 6437.5 6704.0 6795.5 6803.5 6858.3 6884.4 4756.7 6342.3 6375.2 6375.4 6378.3 6057.5 6061.8 6065.1 6086.1 6093.6 6787.3 6885.9 6942.9 7020.4 7043.5 6821.4 6915.5 6921.8 6927.3 6939.9 6938.4 7013.4 7063.6 7081.8 7112.3 6397.6 6408.3 6494.7 6570.5 6761.2 5764.7 6699.4 6876.3 6917.3 6925.8 5203.9 6288.3 6499.5 6510.5 6571.0 6403.7 6492.2 6516.3 6525.7 6539.6 6290.4 6358.4 6432.7 6450.9 6453.4 6535.5 6558.7 6563.5 6565.0 6567.0 6172.6 6178.6 6297.1 6387.8 6390.0 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:44739.31 Median:182576.00 stddev:77598.93 (42.5%) stddev-sans-first:77746.21 19:21:51 INFO - PID 1635 | Values: 712.8 753.7 832.3 1206.7 1255.8 37900.0 38906.0 38910.0 38938.0 39045.0 38410.0 38834.8 38838.0 38856.0 38875.6 7830.9 7973.0 8276.2 8481.8 8503.0 8449.3 8473.9 8542.4 8576.0 8605.6 8539.7 8575.8 8575.9 8612.4 8633.7 8416.4 8543.5 8547.7 8561.0 8562.9 8418.0 8460.7 8463.4 8472.6 8485.2 38225.8 38285.8 38473.8 38498.8 38516.8 3564.4 3602.3 3603.0 3606.3 3616.4 31108.4 31164.0 31177.0 31258.4 31271.0 3052.8 3080.5 3087.1 3090.2 3090.8 242436.0 289645.0 293370.0 293722.0 295601.0 48678.5 48971.0 49057.3 49171.5 49218.8 45107.5 49443.8 52163.0 54449.2 54658.7 14467.9 14546.3 14726.3 14737.0 14746.2 11673.8 11768.6 11776.2 11780.5 11780.8 11284.5 11310.8 13936.9 14022.8 14083.9 6225.2 6255.0 6272.2 6297.6 6404.9 822.4 1042.1 1042.5 1050.1 1075.5 20196.9 24550.9 24827.6 25018.7 25315.9 1017.5 1125.8 1132.7 1149.9 1161.6 12802.8 12806.2 12816.4 12829.4 12840.7 326134.7 326580.0 329606.0 329841.1 330074.0 53734.5 54381.4 54435.0 54661.0 54723.5 1315.3 1465.2 1528.9 1549.2 1558.0 16799.9 24159.5 24206.8 24377.0 24423.5 38060.2 38082.0 38191.6 38248.4 38292.4 271613.6 271927.0 272921.0 273218.9 274637.0 35078.0 35302.3 35380.8 35475.5 35553.3 42851.2 43210.8 44200.1 44741.6 44756.7 29309.3 29972.9 30142.8 30865.0 31227.2 30246.0 30488.8 30535.7 30821.8 30878.5 35698.5 35801.3 35918.6 36005.0 36777.6 24346.5 29976.0 30642.0 30836.7 30984.8 19573.6 20264.0 22910.0 23825.4 24085.8 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:35833.23 Median:23020.54 stddev:94325.28 (409.7%) stddev-sans-first:94552.44 19:21:51 INFO - PID 1635 | Values: 431.8 436.6 440.8 456.5 459.5 16937.5 22059.7 22151.9 22420.9 22691.5 22879.8 22883.7 23329.3 23519.6 23737.0 6098.1 6228.7 6248.0 6295.4 6304.1 5987.3 6237.8 6272.2 6273.2 6282.8 5983.0 6158.1 6216.2 6233.7 6251.1 6143.2 6166.7 6217.4 6218.9 6219.6 5393.1 5536.4 5543.3 5584.4 5588.1 26303.7 27033.9 28549.6 28748.9 28867.6 2195.8 2276.5 2276.8 2277.4 2280.4 14424.9 17365.6 17886.6 17909.9 18474.0 2150.6 2189.4 2189.5 2196.5 2212.2 126203.0 148577.0 151072.0 155638.2 165853.0 12243.9 12761.7 12864.7 12868.4 12914.6 16362.9 16714.5 16733.0 16747.2 16754.5 8947.0 9123.3 9198.9 9229.0 9331.6 7310.4 8669.4 8698.7 8771.0 8979.4 7947.2 8070.8 8096.3 8099.0 8107.5 2145.7 2149.4 2151.1 2151.4 2306.6 821.9 858.2 933.7 934.2 1014.9 20833.5 21306.5 21376.3 21499.4 21537.0 859.8 1017.6 1050.5 1060.6 1063.0 10682.3 10788.8 13226.7 13749.2 14300.9 525648.0 563792.0 565669.7 566706.0 567415.0 104278.0 126100.4 131805.0 132621.0 135762.5 1216.0 1244.8 1249.0 1261.4 1264.8 20221.8 20330.4 22349.0 23012.4 23028.7 55712.0 56331.0 56658.7 57558.0 57965.0 80880.0 80976.6 81230.8 81453.0 81484.2 8683.9 8835.1 8954.9 9191.4 9222.7 13712.5 14452.2 14563.6 14756.1 15000.7 11969.0 12173.3 12202.1 12346.6 12392.4 8293.0 9037.4 10506.3 10670.5 10850.6 12318.5 12379.8 12553.7 12591.6 13404.2 6898.3 7454.7 8769.4 8859.4 9016.9 9387.5 9611.7 12131.5 12241.6 12290.1 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7000.27 Median:3651.90 stddev:4422.37 (121.1%) stddev-sans-first:4414.30 19:21:51 INFO - PID 1635 | Values: 1336.1 1475.4 1488.1 1489.4 1495.3 8745.8 11130.7 11731.9 11766.9 11929.6 11535.5 11707.9 11708.5 11709.7 11739.1 2475.4 2534.3 2537.5 2545.2 2549.0 2477.7 2504.2 2528.5 2532.6 2541.0 2463.9 2477.0 2508.8 2520.1 2522.0 2472.1 2488.0 2504.7 2507.1 2507.5 2424.9 2483.4 2485.9 2487.6 2521.3 9689.2 11607.6 11808.5 11813.9 11908.6 6519.2 6756.2 6763.8 6766.0 6781.9 3409.3 3426.7 3648.8 3655.0 4318.6 1058.0 1148.0 1280.6 1288.1 1292.9 14797.4 15929.2 16289.9 16309.8 16499.4 5486.3 5564.2 5571.8 5721.7 5731.5 11687.1 11812.2 11843.1 11854.4 11886.2 6517.7 6533.9 6540.5 6541.1 6543.0 5970.9 6078.5 6186.0 6229.9 6231.8 3905.8 4201.4 4207.5 4383.5 4434.9 1240.3 1251.6 1258.4 1266.4 1266.4 770.8 796.7 809.8 810.3 829.5 6124.6 6142.8 6306.1 6354.5 6362.0 578.5 583.2 593.4 601.9 606.5 4470.0 4473.9 4515.4 4658.1 4666.5 14965.9 14980.3 15193.7 15227.8 15233.5 10620.0 14803.6 14820.6 14882.3 15076.9 8241.2 8318.9 8455.9 8534.9 8551.4 6702.4 6803.4 7011.4 7062.5 7066.3 12138.1 13113.3 13476.4 13556.9 13569.5 13266.1 13595.3 13697.3 13938.4 13997.4 8330.2 9003.5 9187.7 9339.6 9445.6 10351.1 10509.1 10548.0 10655.3 10661.9 7560.3 7719.9 8849.0 9040.3 9156.0 8816.9 8848.6 8936.3 8970.0 9142.0 7959.4 8083.5 8184.9 8194.2 8206.9 5997.8 6001.6 6096.2 6114.9 6210.3 5326.6 5959.6 6040.3 6111.4 6230.2 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5337.23 Median:5979.28 stddev:1706.46 (28.5%) stddev-sans-first:1669.34 19:21:51 INFO - PID 1635 | Values: 329.5 395.3 401.5 403.1 407.5 5155.3 6168.4 6269.8 6297.8 6524.3 6246.7 6309.8 6321.6 6356.3 6359.9 4418.8 4421.4 4532.1 4546.5 4549.2 4536.3 4537.9 4540.2 4550.5 4561.6 4504.5 4511.7 4513.7 4515.6 4526.4 3987.9 4323.0 4482.4 4522.9 4530.6 3512.9 3763.5 3853.9 3859.5 3865.2 4933.8 5066.0 5082.0 5960.9 6101.0 5286.9 5414.0 5460.5 5477.7 5486.8 5563.8 6731.3 6740.4 6781.5 6785.0 4245.4 4376.6 4383.7 4383.8 4400.4 5938.5 6569.7 6989.9 7186.3 7277.3 4402.5 4453.2 4457.0 4458.8 4484.8 4530.1 4532.3 4536.6 4545.5 4547.4 5339.9 5377.7 5444.8 5464.2 5484.6 6849.5 6857.5 6858.5 6868.1 6880.2 1263.8 1444.6 1539.1 1542.2 1545.2 849.0 870.2 923.5 945.9 995.8 5966.4 6133.9 6434.5 6434.6 6454.7 5911.8 6882.9 7088.0 7105.6 7111.6 6350.5 6368.4 6380.6 6405.5 6421.6 3243.3 3545.8 3635.7 3681.7 3686.5 6664.0 6937.7 7195.9 7202.2 7246.4 5939.0 5992.1 6456.5 7095.4 7251.3 5533.9 6991.0 7181.5 7208.9 7254.2 6402.4 6882.6 6946.5 7004.3 7069.7 6302.7 6496.6 6837.9 6862.7 6889.5 5612.7 7031.9 7202.9 7245.5 7269.8 4391.1 4393.0 4402.7 4406.9 4437.4 5534.4 6365.4 6889.0 6970.0 6973.9 5106.9 6208.9 6643.3 6654.5 6707.2 6035.9 6280.8 6478.3 6499.3 6556.5 5392.6 5465.1 5756.6 6504.6 6582.7 5168.5 5541.6 5758.9 6675.2 6685.3 5799.4 6010.6 6083.8 6099.7 6460.4 19:21:51 INFO - PID 1635 | 19:21:51 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21456.48 Median:18909.12 stddev:29144.31 (154.1%) stddev-sans-first:29184.49 19:21:51 INFO - PID 1635 | Values: 725.5 757.3 804.5 821.8 841.9 13714.8 15175.5 16577.7 18601.1 19152.6 17650.6 17984.1 17988.4 18114.5 18250.8 970.5 998.4 1002.0 1002.6 1006.3 995.0 996.9 997.0 997.1 1004.4 1046.4 1047.2 1059.6 1063.2 1069.8 1037.7 1058.0 1059.2 1064.3 1064.5 714.9 738.9 771.9 789.9 792.8 15620.5 15983.3 16020.6 16020.8 16066.4 16611.8 17044.6 17216.0 17435.5 17462.6 13642.8 13834.9 14087.5 14153.5 14177.6 3432.1 3481.1 3484.4 3489.4 3500.4 105192.0 111744.9 112060.0 125252.7 125446.0 12735.3 13169.5 13219.1 13299.4 13326.3 23918.9 24269.5 24608.3 24664.4 24739.0 7795.8 8009.6 8055.8 8111.3 8111.4 3234.6 3963.3 4042.5 4043.9 4048.3 4278.1 4314.3 4373.4 4404.5 4422.0 918.2 950.4 955.5 960.2 965.0 637.7 654.5 656.4 661.4 688.7 10924.5 11883.2 11962.3 12827.9 13320.5 641.7 649.5 671.9 685.4 694.8 5320.4 5374.4 5415.7 5427.8 5472.0 84299.2 91444.5 98565.0 99835.0 101423.5 78683.5 79310.6 80492.0 86312.0 90089.0 74717.6 76586.2 82189.0 84911.6 89372.0 11059.6 11100.6 12275.3 12284.7 12534.1 39309.0 41119.8 41329.0 42864.1 43852.1 60992.1 67054.0 69286.0 71843.0 74194.9 16304.6 16765.3 17059.8 17359.5 17501.7 18269.6 18870.5 18947.7 19083.5 19128.4 13005.0 16633.8 16675.0 17381.1 17445.3 13609.8 15544.8 17224.7 17463.8 17612.0 13970.0 16323.0 16526.3 16944.8 17133.1 11998.6 12006.3 12137.8 12150.7 12427.3 12815.2 13488.7 13517.3 13523.3 13540.0 19:21:51 INFO - PID 1635 | -------- Summary: end -------- 19:21:51 INFO - PID 1635 | 19:21:52 INFO - TEST-INFO | 1635: exit 0 19:21:52 INFO - TEST-OK | dromaeo_css | took 1115275ms 19:21:52 INFO - TEST-START | kraken 19:21:52 INFO - operating with platform_type : mac_ 19:21:52 INFO - Initialising browser for kraken test... 19:21:52 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp1QLzpP/profile http://localhost:49229/getInfo.html) 19:21:55 INFO - PID 1648 | 2017-05-02 19:21:55.094 plugin-container[1649:9998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 19:21:55 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:55 INFO - PID 1648 | 2017-05-02 19:21:55.098 plugin-container[1649:9998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 19:21:55 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:55 INFO - PID 1648 | 2017-05-02 19:21:55.098 plugin-container[1649:9998] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:21:55 INFO - PID 1648 | __metrics Screen width/height:1600/1200 19:21:55 INFO - PID 1648 | colorDepth:24 19:21:55 INFO - PID 1648 | Browser inner width/height: 1024/691 19:21:55 INFO - PID 1648 | __metrics 19:21:56 INFO - TEST-INFO | 1648: exit 0 19:21:56 INFO - Browser initialized. 19:21:56 INFO - Running cycle 1/1 for kraken test... 19:21:56 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp1QLzpP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:21:58 INFO - PID 1650 | 2017-05-02 19:21:58.966 plugin-container[1651:10122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 19:21:58 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:58 INFO - PID 1650 | 2017-05-02 19:21:58.966 plugin-container[1651:10122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 19:21:58 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:58 INFO - PID 1650 | 2017-05-02 19:21:58.967 plugin-container[1651:10122] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:08 INFO - PID 1650 | RSS: Main: 251744256 19:22:08 INFO - PID 1650 | 19:22:11 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 19:22:12 INFO - PID 1650 | RSS: Main: 258994176 19:22:12 INFO - PID 1650 | 19:22:15 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 19:22:15 INFO - PID 1650 | RSS: Main: 266149888 19:22:15 INFO - PID 1650 | 19:22:19 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 19:22:20 INFO - PID 1650 | RSS: Main: 262053888 19:22:20 INFO - PID 1650 | 19:22:22 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 19:22:22 INFO - PID 1650 | RSS: Main: 263245824 19:22:22 INFO - PID 1650 | 19:22:24 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:22:25 INFO - PID 1650 | RSS: Main: 265027584 19:22:25 INFO - PID 1650 | 19:22:28 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 19:22:29 INFO - PID 1650 | RSS: Main: 270696448 19:22:29 INFO - PID 1650 | 19:22:31 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 19:22:32 INFO - PID 1650 | RSS: Main: 270815232 19:22:32 INFO - PID 1650 | 19:22:35 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 19:22:36 INFO - PID 1650 | RSS: Main: 270741504 19:22:36 INFO - PID 1650 | 19:22:37 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:22:38 INFO - PID 1650 | RSS: Main: 270577664 19:22:38 INFO - PID 1650 | 19:22:40 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:22:40 INFO - PID 1650 | RSS: Main: 270618624 19:22:40 INFO - PID 1650 | 19:22:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:43 INFO - PID 1650 | RSS: Main: 270249984 19:22:43 INFO - PID 1650 | 19:22:46 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:47 INFO - PID 1650 | RSS: Main: 270491648 19:22:47 INFO - PID 1650 | 19:22:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:51 INFO - PID 1650 | RSS: Main: 270536704 19:22:51 INFO - PID 1650 | 19:22:52 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 19:22:53 INFO - PID 1650 | RSS: Main: 272191488 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | __start_tp_report 19:22:53 INFO - PID 1650 | _x_x_mozilla_page_load 19:22:53 INFO - PID 1650 | _x_x_mozilla_page_load_details 19:22:53 INFO - PID 1650 | |i|pagename|runs| 19:22:53 INFO - PID 1650 | |0;ai-astar;133;171;105;169;168;154;116;115;116;116 19:22:53 INFO - PID 1650 | |1;audio-beat-detection;146;136;90;173;140;111;129;113;145;112 19:22:53 INFO - PID 1650 | |2;audio-dft;218;265;206;211;197;204;208;228;242;288 19:22:53 INFO - PID 1650 | |3;audio-fft;81;56;57;87;51;77;84;50;87;78 19:22:53 INFO - PID 1650 | |4;audio-oscillator;84;70;84;72;84;105;86;69;77;90 19:22:53 INFO - PID 1650 | |5;imaging-gaussian-blur;93;89;89;95;119;137;88;88;100;88 19:22:53 INFO - PID 1650 | |6;imaging-darkroom;95;96;96;96;97;96;97;96;94;96 19:22:53 INFO - PID 1650 | |7;imaging-desaturate;115;90;90;90;89;108;149;110;150;89 19:22:53 INFO - PID 1650 | |8;json-parse-financial;51;48;52;52;53;53;59;76;75;50 19:22:53 INFO - PID 1650 | |9;json-stringify-tinderbox;57;62;75;66;63;59;55;55;54;55 19:22:53 INFO - PID 1650 | |10;stanford-crypto-aes;83;80;84;82;87;78;85;72;95;111 19:22:53 INFO - PID 1650 | |11;stanford-crypto-ccm;142;161;154;137;138;138;137;139;220;137 19:22:53 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;192;189;180;185;184;187;217;183;185;190 19:22:53 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;71;70;67;71;69;82;85;101;68;69 19:22:53 INFO - PID 1650 | __end_tp_report 19:22:53 INFO - PID 1650 | __start_cc_report 19:22:53 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1013 19:22:53 INFO - PID 1650 | __end_cc_report 19:22:53 INFO - PID 1650 | __startTimestamp1493778173169__endTimestamp 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | ------- Summary: start ------- 19:22:53 INFO - PID 1650 | Number of tests: 14 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:136.30 Median:143.50 stddev:26.40 (18.4%) stddev-sans-first:27.97 19:22:53 INFO - PID 1650 | Values: 133.0 171.0 105.0 169.0 168.0 154.0 116.0 115.0 116.0 116.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:142.50 stddev:23.66 (16.6%) stddev-sans-first:24.33 19:22:53 INFO - PID 1650 | Values: 146.0 136.0 90.0 173.0 140.0 111.0 129.0 113.0 145.0 112.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:226.70 Median:223.00 stddev:29.74 (13.3%) stddev-sans-first:31.37 19:22:53 INFO - PID 1650 | Values: 218.0 265.0 206.0 211.0 197.0 204.0 208.0 228.0 242.0 288.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:70.80 Median:79.50 stddev:15.38 (19.3%) stddev-sans-first:15.86 19:22:53 INFO - PID 1650 | Values: 81.0 56.0 57.0 87.0 51.0 77.0 84.0 50.0 87.0 78.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:84.00 stddev:10.85 (12.9%) stddev-sans-first:11.48 19:22:53 INFO - PID 1650 | Values: 84.0 70.0 84.0 72.0 84.0 105.0 86.0 69.0 77.0 90.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:88.50 stddev:16.53 (18.7%) stddev-sans-first:17.41 19:22:53 INFO - PID 1650 | Values: 93.0 89.0 89.0 95.0 119.0 137.0 88.0 88.0 100.0 88.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 19:22:53 INFO - PID 1650 | Values: 95.0 96.0 96.0 96.0 97.0 96.0 97.0 96.0 94.0 96.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:108.00 Median:89.00 stddev:24.02 (27.0%) stddev-sans-first:25.34 19:22:53 INFO - PID 1650 | Values: 115.0 90.0 90.0 90.0 89.0 108.0 149.0 110.0 150.0 89.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:53.00 stddev:10.20 (19.3%) stddev-sans-first:10.60 19:22:53 INFO - PID 1650 | Values: 51.0 48.0 52.0 52.0 53.0 53.0 59.0 76.0 75.0 50.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.50 stddev:6.62 (10.9%) stddev-sans-first:6.93 19:22:53 INFO - PID 1650 | Values: 57.0 62.0 75.0 66.0 63.0 59.0 55.0 55.0 54.0 55.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:83.50 stddev:10.71 (12.8%) stddev-sans-first:11.31 19:22:53 INFO - PID 1650 | Values: 83.0 80.0 84.0 82.0 87.0 78.0 85.0 72.0 95.0 111.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:140.50 stddev:25.85 (18.4%) stddev-sans-first:27.25 19:22:53 INFO - PID 1650 | Values: 142.0 161.0 154.0 137.0 138.0 138.0 137.0 139.0 220.0 137.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:188.00 stddev:10.39 (5.5%) stddev-sans-first:10.97 19:22:53 INFO - PID 1650 | Values: 192.0 189.0 180.0 185.0 184.0 187.0 217.0 183.0 185.0 190.0 19:22:53 INFO - PID 1650 | 19:22:53 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:70.50 stddev:10.88 (15.4%) stddev-sans-first:11.43 19:22:53 INFO - PID 1650 | Values: 71.0 70.0 67.0 71.0 69.0 82.0 85.0 101.0 68.0 69.0 19:22:53 INFO - PID 1650 | -------- Summary: end -------- 19:22:53 INFO - PID 1650 | 19:22:54 INFO - TEST-INFO | 1650: exit 0 19:22:54 INFO - TEST-OK | kraken | took 61964ms 19:22:54 INFO - SUITE-END | took 1177s 19:22:54 INFO - Completed test suite (00:19:37) 19:22:54 INFO - javascript benchmark 19:22:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7885.454129685452, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3622.6739593436587, 3630.0198946284504, 3640.5631324241094, 3644.6902013328868, 3656.9634303656953, 6108.402780883657, 6205.472534834539, 6237.5009999200065, 6263.029230469273, 6284.780032698856, 5088.694678319294, 6209.751609935597, 6213.068039794033, 6281.685915704219, 6296.779612713556, 5426.240326354314, 5438.619296649236, 5449.5640348772, 5455.1271796512565, 5471.644343117705, 5509.586780991422, 5566.832995010156, 5589.804356847511, 5651.6043876928425, 5654.971725141369, 5375.489328513785, 5488.368837583649, 5513.889722205534, 5564.527015203713, 5571.4428557144365, 5437.510624043815, 5467.371252305996, 5495.505404513573, 5524.668519888779, 5571.498565129158, 5380.623356365056, 5425.782968681248, 5448.509634132907, 5466.590005749585, 5467.972660136714, 6032.999999999956, 6288.559800813945, 6296.590721603127, 6307.936920630781, 6323.177986861724, 5835.562332825013, 6414.134091897581, 6505.707243174069, 6720.697568609425, 6750.35871592198, 6856.657167141673, 6868.965655171743, 6888.0, 6904.171499420051, 6914.688839002257, 6226.346233645492, 6386.840328991766, 6391.520635952275, 6401.487880969511, 6412.294647588761, 5549.5837812163445, 6722.327767223239, 6831.829204269834, 6849.657517124124, 7022.719091236293, 6422.0, 6498.155239818888, 6532.608043517404, 6562.212534495891, 6644.468442524587, 5571.247881536022, 6681.5656982296, 6819.147606549182, 6827.829304267334, 6868.347506986828, 6690.933090669128, 6728.899066514006, 6738.764143255061, 6740.39336459721, 6750.392464678104, 6343.3339499351905, 6595.241547222035, 6685.732570697118, 6687.929931210982, 6773.153355830521, 5277.1292736698015, 5331.546818520469, 5360.544353729977, 5389.622726409114, 5426.430224826337, 4761.4524329701835, 4772.451168115642, 4781.832635857728, 4782.952170478251, 4843.975780121077, 6180.845478863064, 6401.359864013654, 6416.967915160488, 6425.228972523327, 6437.485001199986, 6704.0, 6795.456363490909, 6803.48973826965, 6858.348456896587, 6884.414824739853, 4756.738379389098, 6342.270638876496, 6375.234971803413, 6375.4262116409745, 6378.266499352541, 6057.454829065317, 6061.7575296987625, 6065.059915713072, 6086.122308988576, 6093.634381937098, 6787.321267873173, 6885.896711549431, 6942.895856562054, 7020.368166865007, 7043.471739619446, 6821.420179284816, 6915.515913886181, 6921.757738479129, 6927.272636373108, 6939.930600693929, 6938.375546200866, 7013.368796808108, 7063.57618542889, 7081.752138675121, 7112.288771122846, 6397.616143031433, 6408.263049749338, 6494.6752662367635, 6570.5400621957915, 6761.22245941723, 5764.740586673526, 6699.39705426514, 6876.346747059021, 6917.342852429011, 6925.792226233221, 5203.92194117081, 6288.339724329063, 6499.545031847725, 6510.47916166715, 6570.967145164434, 6403.679816009274, 6492.220933488012, 6516.315786842503, 6525.673716314261, 6539.574927629689, 6290.370962903856, 6358.364163583789, 6432.710528026157, 6450.903236451551, 6453.386928241809, 6535.477161827138, 6558.737650493927, 6563.474922006323, 6565.0, 6567.014947757874, 6172.567920245539, 6178.567500275118, 6297.055441683601, 6387.840303992437, 6390.0], "value": 6129.00622997212, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8111050571598, 753.703629756818, 832.2717622080679, 1206.722453835618, 1255.7927941889589, 37900.0, 38906.0, 38910.0, 38938.0, 39045.02387440315, 38410.03974900622, 38834.805825970834, 38838.0, 38856.02859928503, 38875.63935262266, 7830.882536761939, 7973.0, 8276.172382761735, 8481.787955301104, 8503.0, 8449.32405407569, 8473.872891906627, 8542.402031857773, 8576.0, 8605.61274742635, 8539.74380768578, 8575.82848343036, 8575.914240857604, 8612.39713220075, 8633.69782057628, 8416.368772342068, 8543.487390756543, 8547.74356769298, 8560.957195214047, 8562.914370856273, 8417.95791021047, 8460.66157353705, 8463.4075614707, 8472.576371181478, 8485.193906578865, 38225.80887095575, 38285.80857095725, 38473.80763096194, 38498.80750596257, 38516.80741596274, 3564.3940530109944, 3602.3335682898746, 3602.963970360289, 3606.278744251134, 3616.439451884962, 31108.37783244345, 31164.0, 31177.0, 31258.37483250345, 31271.0, 3052.771042171824, 3080.476319025771, 3087.058447173611, 3090.227443139215, 3090.7990980586414, 242436.0, 289645.0, 293370.0, 293722.0, 295600.99999999785, 48678.51321486811, 48971.02057958821, 49057.264141037915, 49171.508284916694, 49218.75390623024, 45107.54892451099, 49443.75278123586, 52163.0, 54449.1832622511, 54658.726706366215, 14467.92766036163, 14546.345414456375, 14726.263686815617, 14737.042092263968, 14746.188959607325, 11673.824892626617, 11768.646940591796, 11776.234544754563, 11780.469878855472, 11780.764384712258, 11284.492197851116, 11310.830337544943, 13936.86063139376, 14022.789658155136, 14083.929580352238, 6225.159603453566, 6254.9687251563455, 6272.153259310026, 6297.590656607397, 6404.903926441107, 822.3708862720024, 1042.1142029275206, 1042.47876061969, 1050.0969166516788, 1075.483368916723, 20196.899015504827, 24550.895209715607, 24827.62758558623, 25018.749062546798, 25315.87342063278, 1017.4759998600726, 1125.786100640892, 1132.694965744722, 1149.8505194324684, 1161.6456980620992, 12802.80795788064, 12806.167599106027, 12816.35918204086, 12829.358532073358, 12840.743185136433, 326134.73865261517, 326580.0, 329606.0, 329841.0523842144, 330074.0, 53734.46265537295, 54381.36855894329, 54435.0, 54661.0, 54723.45276547263, 1315.2700251360395, 1465.1721777196062, 1528.9221099125311, 1549.1944189021647, 1558.0340189082842, 16799.91600042041, 24159.51680966406, 24206.757932421155, 24377.02491900304, 24423.511529768948, 38060.238795223384, 38082.0, 38191.61808381881, 38248.42627360648, 38292.425613616375, 271613.64193178905, 271927.0, 272921.0, 273218.9017164704, 274637.0, 35078.0, 35302.29395412129, 35380.823095884356, 35475.46786798148, 35553.28893422169, 42851.215085963, 43210.78394608007, 44200.05548289907, 44741.55258447374, 44756.65730028104, 29309.26726831846, 29972.85013574918, 30142.84928575343, 30865.0, 31227.219319517193, 30246.0, 30488.84755576208, 30535.694643053284, 30821.845890770404, 30878.53682194724, 35698.46452303269, 35801.28397432089, 35918.640813591526, 36005.0, 36777.63222367849, 24346.51306973815, 29976.0, 30642.0, 30836.69163308428, 30984.760609575365, 19573.62984591065, 20263.986800660205, 22910.0, 23825.404364891016, 24085.759142409053], "value": 17534.751162726432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.8056874406518, 436.64849795914284, 440.83909373078814, 456.52293353445646, 459.4647235970095, 16937.491875243748, 22059.66910496344, 22151.889240553795, 22420.88789556054, 22691.54616907661, 22879.77120228797, 22883.656745148837, 23329.3001209964, 23519.647205291934, 23737.0, 6098.054801505763, 6228.719707613168, 6248.0, 6295.370462953714, 6304.117423560682, 5987.251593550806, 6237.844053898666, 6272.153259310003, 6273.184486016811, 6282.811515654515, 5983.0, 6158.107074474198, 6216.222972128484, 6233.657148856811, 6251.062340648894, 6143.232095988002, 6166.7224974876235, 6217.378262173746, 6218.906716399258, 6219.564630475868, 5393.083175860113, 5536.390996990328, 5543.334799824047, 5584.441555844423, 5588.133839254922, 26303.73696263051, 27033.86483067572, 28549.57175642367, 28748.850045998133, 28867.566986495218, 2195.8133558647373, 2276.46503071778, 2276.84062115652, 2277.4420267034684, 2280.372897453197, 14424.927875360661, 17365.565860853454, 17886.642267154715, 17909.910450447664, 18474.0, 2150.559135377244, 2189.4416923684357, 2189.4745261137214, 2196.461866842618, 2212.2478357358577, 126203.0, 148577.0, 151071.97856042816, 155638.22180889023, 165853.0, 12243.877561224452, 12761.744765104646, 12864.678383040311, 12868.420921058574, 12914.612561623166, 16362.918185409235, 16714.498565043068, 16733.0, 16747.246373913204, 16754.497365079067, 8947.0, 9123.270138388913, 9198.862017069749, 9229.030951750105, 9331.620436788575, 7310.378617817439, 8669.445527333763, 8698.652053917898, 8771.0, 8979.416337938013, 7947.205279472007, 8070.757877263692, 8096.311813495919, 8099.0, 8107.473014254055, 2145.742510898702, 2149.430400943751, 2151.1072904744356, 2151.354593621907, 2306.619407797728, 821.9219174178444, 858.2447446247262, 933.748776639293, 934.1834369376336, 1014.8680671512656, 20833.479163020744, 21306.467338316666, 21376.251831185833, 21499.355019349445, 21537.0, 859.7936495241097, 1017.6133069433716, 1050.5430137890041, 1060.5810704771727, 1062.9946850265853, 10682.252242342962, 10788.78422431547, 13226.669333266746, 13749.175049497062, 14300.85699143016, 525648.0, 563792.0, 565669.6905282283, 566706.0, 567415.0, 104277.95722042835, 126100.36949815376, 131805.0, 132621.0, 135762.53306068262, 1215.9817602735966, 1244.794608889523, 1248.9687757806128, 1261.394530625305, 1264.8292480515013, 20221.79778202258, 20330.390088297376, 22349.0, 23012.4246893829, 23028.654570181126, 55712.0, 56331.0, 56658.716706416206, 57558.0, 57965.0, 80880.0, 80976.59511702404, 81230.7815382758, 81453.0, 81484.18515815004, 8683.913160868564, 8835.116488351114, 8954.910450895408, 9191.356605037583, 9222.677206297747, 13712.52006179779, 14452.205128718022, 14563.563093107225, 14756.114633121617, 15000.699986000001, 11969.042476602026, 12173.330466824409, 12202.14584979043, 12346.567870124503, 12392.442340094898, 8293.004839419309, 9037.412568183046, 10506.264561480622, 10670.46647667598, 10850.565977360817, 12318.50725970987, 12379.814302785646, 12553.686157846127, 12591.559295424617, 13404.195748255135, 6898.344657257552, 7454.739084132029, 8769.429987050822, 8859.3798434109, 9016.864747028669, 9387.530623468663, 9611.663591774255, 12131.45408456604, 12241.571544995883, 12290.078244131548], "value": 10569.924294181677, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.138190866891, 1475.38771409865, 1488.107135718569, 1489.3595753825864, 1495.2673190136834, 8745.7813554661, 11130.666080017592, 11731.941340293308, 11766.882331176696, 11929.642110736691, 11535.480903359348, 11707.882921170785, 11708.531658733662, 11709.70725731857, 11739.119566032561, 2475.443025319307, 2534.2650631316988, 2537.4671319023028, 2545.210984594772, 2549.0441084593276, 2477.7150627677865, 2504.161106029482, 2528.506941146477, 2532.5821239495463, 2541.034406925375, 2463.9014439422403, 2477.0463371601973, 2508.7742103210708, 2520.143151328548, 2521.9747802521924, 2472.11004038546, 2488.042103790036, 2504.686914135733, 2507.122507122511, 2507.5361058204107, 2424.9151279705125, 2483.4039830440743, 2485.8757062147, 2487.589547724623, 2521.2940376694555, 9689.224862011093, 11607.593734219347, 11808.46861891217, 11813.881861181364, 11908.583199587973, 6519.185101862267, 6756.189257289157, 6763.763268285636, 6766.018927255521, 6781.932180678229, 3409.2840503494294, 3426.6573342665783, 3648.7810731356203, 3655.0131464504434, 4318.568143185687, 1057.973550661232, 1147.9668298531305, 1280.5582074184392, 1288.072587736828, 1292.9030322725832, 14797.40810367584, 15929.203539823078, 16289.91855040729, 16309.755353669705, 16499.422520211854, 5486.341639003265, 5564.19319198722, 5571.804986825442, 5721.685307308138, 5731.484166425042, 11687.065034797195, 11812.232204906653, 11843.052555795515, 11854.407279635983, 11886.227395219283, 6517.739290428424, 6533.869322613521, 6540.4767618590395, 6541.084248205255, 6543.0, 5970.880582388328, 6078.45293923549, 6186.041163619646, 6229.906551401733, 6231.844203894848, 3905.8477749063527, 4201.432806571073, 4207.474065741782, 4383.531516941796, 4434.889127771767, 1240.344140572346, 1251.5869762978195, 1258.4399942025893, 1266.3858028855998, 1266.4364357860663, 770.7803276066294, 796.6773456750026, 809.8218391953761, 810.3152835853695, 829.5147338806745, 6124.601900876429, 6142.754289828356, 6306.085617585424, 6354.523410744213, 6362.0, 578.4591407034436, 583.186454895427, 593.3828818029288, 601.8555546668855, 606.4693393280879, 4469.955300447019, 4473.865784026484, 4515.435570553681, 4658.138244424792, 4666.4633567139535, 14965.92517037408, 14980.250987450583, 15193.696126077417, 15227.771583426258, 15233.466828660943, 10620.0, 14803.555893323217, 14820.555383338517, 14882.330295136744, 15076.924615376853, 8241.175882411711, 8318.875216871751, 8455.873161902577, 8534.87197692035, 8551.358648101294, 6702.396784289437, 6803.353681400259, 7011.404030657452, 7062.505624606229, 7066.258042905421, 12138.089643276966, 13113.27876966776, 13476.393562289524, 13556.864431355954, 13569.457221711022, 13266.137701049402, 13595.252261125734, 13697.315134243048, 13938.442462301395, 13997.370118344495, 8330.208630180123, 9003.54982250898, 9187.724368268962, 9339.57971891253, 9445.622175113192, 10351.120154786928, 10509.106725928381, 10548.0, 10655.30740501865, 10661.946690266499, 7560.281773231534, 7719.92280077192, 8849.0, 9040.321975851968, 9156.03861594523, 8816.911830881609, 8848.64605415802, 8936.329775267011, 8970.0, 9142.0, 7959.363250939795, 8083.474574152661, 8184.8772268417215, 8194.22154895284, 8206.876896846432, 5997.83042306746, 6001.639901605918, 6096.237970253718, 6114.908276375769, 6210.3168651449205, 5326.573874090141, 5959.642421454727, 6040.305364883095, 6111.358307377662, 6230.221222347207], "value": 5263.514101425628, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.4695540180309, 395.28256214969826, 401.530209654704, 403.12119579317095, 407.5170922456889, 5155.329807125068, 6168.414000669941, 6269.780557680482, 6297.779577714781, 6524.347565243474, 6246.687665616723, 6309.842253943653, 6321.620702757827, 6356.3008069112475, 6359.872802543946, 4418.779061046935, 4421.358902959069, 4532.13889361022, 4546.499885012646, 4549.24937385331, 4536.319552067183, 4537.863864084083, 4540.18276710192, 4550.451747021679, 4561.635069194473, 4504.527024662396, 4511.661625378109, 4513.706609070417, 4515.571020753023, 4526.388937493429, 3987.920241595181, 4322.956770432303, 4482.417285752831, 4522.8643140705835, 4530.59224669778, 3512.947305790415, 3763.510743603314, 3853.8843834685003, 3859.536855577349, 3865.246276975991, 4933.753312334369, 5065.97361055577, 5081.992612917914, 5960.910586341209, 6101.0, 5286.894262114736, 5413.996821176151, 5460.453954604547, 5477.726113694339, 5486.807961721321, 5563.833085007415, 6731.2932142125355, 6740.359665831738, 6781.457483401366, 6785.0, 4245.405643209953, 4376.606105450493, 4383.693141480097, 4383.846565370197, 4400.361947517624, 5938.4761476372805, 6569.704363303663, 6989.860202795967, 7186.269217697241, 7277.272272772733, 4402.493713222956, 4453.22068637987, 4457.04173602677, 4458.799354029012, 4484.820607175677, 4530.116627257653, 4532.27483602622, 4536.637069034469, 4545.499995000548, 4547.408836851254, 5339.866503337447, 5377.677339359651, 5444.794858082483, 5464.153056276284, 5484.628842789302, 6849.452043836481, 6857.457072158724, 6858.485613579001, 6868.111269400954, 6880.208775990725, 1263.759885621729, 1444.6460617148866, 1539.0915265071455, 1542.1749364090158, 1545.2196640696377, 848.9533075680897, 870.1950695606539, 923.4736200365727, 945.8507912885876, 995.7759504111517, 5966.433188847052, 6133.877322453526, 6434.549581529342, 6434.613923164625, 6454.709538070798, 5911.832158328368, 6882.8623427532175, 7088.0, 7105.609191494517, 7111.608861512666, 6350.4602108820345, 6368.426841584187, 6380.649064301415, 6405.48756099511, 6421.614703117828, 3243.3351163011525, 3545.840437180333, 3635.6727894489095, 3681.742278040565, 3686.4654625079215, 6664.0, 6937.7224911002995, 7195.92804071963, 7202.243764404662, 7246.420286377077, 5938.960681880653, 5992.123780585256, 6456.51576131792, 7095.410175285983, 7251.274872512707, 5533.861653458615, 6990.965045174843, 7181.455986962724, 7208.8918666219, 7254.238304978006, 6402.391772781578, 6882.5870447773295, 6946.479014074066, 7004.264552221944, 7069.717211311696, 6302.65335406557, 6496.577722448026, 6837.9059350503685, 6862.725490980305, 6889.483288753464, 5612.691301978431, 7031.897783238864, 7202.927970720435, 7245.529040612343, 7269.818254543467, 4391.099824536026, 4392.956070439254, 4402.691811573287, 4406.867793966058, 4437.42313499243, 5534.418886016911, 6365.363463653783, 6888.967754512531, 6970.024196612377, 6973.919042548413, 5106.923396148986, 6208.906866397098, 6643.3356664335115, 6654.5341826073645, 6707.189058954153, 6035.879282414415, 6280.806646737105, 6478.294964711501, 6499.28507864144, 6556.541042127005, 5392.649477783932, 5465.07093794048, 5756.625819321731, 6504.60972341661, 6582.703778329891, 5168.483151684802, 5541.612087153861, 5758.884822303614, 6675.198976122896, 6685.264620891795, 5799.362070172193, 6010.639361638316, 6083.756649733961, 6099.66451845135, 6460.386263305167], "value": 4791.206261714754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.5247812682693, 757.299861810236, 804.4730701390591, 821.8438496685629, 841.9410641255115, 13714.794278085838, 15175.54473365798, 16577.668446631076, 18601.069946502656, 19152.616947661038, 17650.64698706028, 17984.100794960264, 17988.370407035756, 18114.456566303033, 18250.817491825077, 970.5341436110671, 998.4009594243432, 1001.9549120289604, 1002.6190047781832, 1006.2553710254396, 994.9502524873727, 996.8704068471088, 997.0, 997.0727223681973, 1004.3823048824956, 1046.408779039844, 1047.1988928469727, 1059.5602824827727, 1063.1972860490318, 1069.7967386196638, 1037.6783197208813, 1058.016045078077, 1059.1526778577108, 1064.3347907557777, 1064.542246833861, 714.8641758065951, 738.9039424874732, 771.9382449404034, 789.8973133492667, 792.8295416485425, 15620.453284135114, 15983.280752366172, 16020.599485012852, 16020.759688604563, 16066.357345706158, 16611.750823737653, 17044.573885652833, 17215.96704197752, 17435.476935691822, 17462.563435914075, 13642.795358069638, 13834.93082534591, 14087.506937257147, 14153.504627338096, 14177.645558861008, 3432.090496018557, 3481.1471189558465, 3484.4424892017155, 3489.4068008438626, 3500.4399296112756, 105191.99999999923, 111744.88255117426, 112060.0, 125252.74747252502, 125446.0, 12735.29955852437, 13169.473221071241, 13219.140755850838, 13299.401526931311, 13326.334208223972, 23918.88040559786, 24269.51460970771, 24608.261752147464, 24664.383390415383, 24739.0, 7795.80510487231, 8009.559474228857, 8055.7583272501915, 8111.269985701315, 8111.351091912633, 3234.5956755405573, 3963.3064213762477, 4042.514898212233, 4043.8989025274605, 4048.291548978917, 4278.144371125787, 4314.331278651814, 4373.409589705413, 4404.515503294635, 4422.049259409242, 918.1919910478736, 950.4012472142506, 955.4649396338073, 960.2030314838668, 964.9903500965041, 637.7002808679913, 654.4862283107833, 656.4059526128862, 661.408039804382, 688.7451642892162, 10924.508397122148, 11883.227590206609, 11962.282263064244, 12827.935860320826, 13320.533781317607, 641.6791604197902, 649.5128653509868, 671.8622682350205, 685.4071228387409, 694.8193469697813, 5320.361556613231, 5374.35507739066, 5415.702136382537, 5427.810026649048, 5472.0, 84299.15700842913, 91444.5427772857, 98565.0, 99835.0, 101423.49288253511, 78683.49215554971, 79310.60344698354, 80492.0, 86312.0, 90089.0, 74717.62641186759, 76586.23413765902, 82189.0, 84911.5754421224, 89372.0, 11059.557617695202, 11100.5559777608, 12275.324857132944, 12284.692882678004, 12534.111696438214, 39309.0, 41119.7944010278, 41329.0, 42864.142717146104, 43852.12295754131, 60992.08511872414, 67054.0, 69286.0, 71843.0, 74194.88707669497, 16304.592385190464, 16765.32938682439, 17059.829401705825, 17359.479215623553, 17501.737473938156, 18269.634607308046, 18870.52823679419, 18947.715784263524, 19083.522911927314, 19128.426147215607, 13004.95960323153, 16633.750493742362, 16674.999500030037, 17381.13094345303, 17445.30218791234, 13609.79585306241, 15544.766828497355, 17224.741628875327, 17463.82536174622, 17612.0, 13970.0, 16323.0, 16526.256318465938, 16944.830551694326, 17133.143342833057, 11998.64004079879, 12006.339651319262, 12137.757244855231, 12150.696232594255, 12427.31649759272, 12815.231086134861, 13488.6627834301, 13517.266001547145, 13523.323833808074, 13540.0], "value": 8391.640912821766, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 171.0, 105.0, 169.0, 168.0, 154.0, 116.0, 115.0, 116.0, 116.0], "value": 136.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 136.0, 90.0, 173.0, 140.0, 111.0, 129.0, 113.0, 145.0, 112.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 265.0, 206.0, 211.0, 197.0, 204.0, 208.0, 228.0, 242.0, 288.0], "value": 226.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 56.0, 57.0, 87.0, 51.0, 77.0, 84.0, 50.0, 87.0, 78.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 84.0, 72.0, 84.0, 105.0, 86.0, 69.0, 77.0, 90.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 89.0, 95.0, 119.0, 137.0, 88.0, 88.0, 100.0, 88.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 96.0, 94.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 90.0, 90.0, 90.0, 89.0, 108.0, 149.0, 110.0, 150.0, 89.0], "value": 108.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 52.0, 52.0, 53.0, 53.0, 59.0, 76.0, 75.0, 50.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 75.0, 66.0, 63.0, 59.0, 55.0, 55.0, 54.0, 55.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 84.0, 82.0, 87.0, 78.0, 85.0, 72.0, 95.0, 111.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 161.0, 154.0, 137.0, 138.0, 138.0, 137.0, 139.0, 220.0, 137.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 189.0, 180.0, 185.0, 184.0, 187.0, 217.0, 183.0, 185.0, 190.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 67.0, 71.0, 69.0, 82.0, 85.0, 101.0, 68.0, 69.0], "value": 75.3, "unit": "score"}]}]} 19:22:54 INFO - Return code: 0 19:22:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:22:54 ERROR - # TBPL SUCCESS # 19:22:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:55 INFO - 2017-05-02 19:22:55.004 screenresolution[1656:10521] starting screenresolution argv=screenresolution get 19:22:55 INFO - 2017-05-02 19:22:55.006 screenresolution[1656:10521] Display 0: 1600x1200x32@60 19:22:55 INFO - 2017-05-02 19:22:55.012 screenresolution[1657:10524] starting screenresolution argv=screenresolution list 19:22:55 INFO - Available Modes on Display 0 19:22:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:55 INFO - Intel Iris: 19:22:55 INFO - Chipset Model: Intel Iris 19:22:55 INFO - Type: GPU 19:22:55 INFO - Bus: Built-In 19:22:55 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:55 INFO - Vendor: Intel (0x8086) 19:22:55 INFO - Device ID: 0x0a2e 19:22:55 INFO - Revision ID: 0x0009 19:22:55 INFO - Displays: 19:22:55 INFO - Display: 19:22:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:55 INFO - Display Serial Number: 1600x1200 60 19:22:55 INFO - Main Display: Yes 19:22:55 INFO - Mirror: Off 19:22:55 INFO - Online: Yes 19:22:55 INFO - Rotation: Supported 19:22:55 INFO - Return code: 0 19:22:55 INFO - Running post-action listener: _resource_record_post_action 19:22:55 INFO - [mozharness: 2017-05-03 02:22:55.245730Z] Finished run-tests step (success) 19:22:55 INFO - Running post-run listener: _resource_record_post_run 19:22:55 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39595008; Write bytes: 511837184; Read time: 263; Write time: 1609 19:22:55 INFO - TinderboxPrint: CPU usage
54.0% 19:22:55 INFO - TinderboxPrint: I/O read bytes / time
39,595,008 / 263 19:22:55 INFO - TinderboxPrint: I/O write bytes / time
511,837,184 / 1,609 19:22:55 INFO - TinderboxPrint: CPU idle
2,205.1 (46.0%) 19:22:55 INFO - TinderboxPrint: CPU system
961.4 (20.1%) 19:22:55 INFO - TinderboxPrint: CPU user
1,627.2 (33.9%) 19:22:55 INFO - TinderboxPrint: Swap in / out
248,135,680 / 0 19:22:55 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 195152384; Write bytes: 195106816; Read time: 14998; Write time: 414 19:22:55 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38587392; Write bytes: 299887616; Read time: 250; Write time: 1143 19:22:55 INFO - Running post-run listener: _upload_blobber_files 19:22:55 INFO - Blob upload gear active. 19:22:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:22:55 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 19:22:55 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'] 19:22:55 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 19:22:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:22:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:22:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:22:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:57 INFO - (blobuploader) - INFO - Done attempting. 19:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:22:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:58 INFO - (blobuploader) - INFO - Done attempting. 19:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:58 INFO - (blobuploader) - INFO - Done attempting. 19:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:23:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:01 INFO - (blobuploader) - INFO - Done attempting. 19:23:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:23:01 INFO - Return code: 0 19:23:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:23:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:23:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c3d33b3d7ab38fb58888584d40cbcaf1d946f7dd47635aa98220973969b9e64950c394c87bac5b1671fa7b86e7a146079de86b500ab53ec5303d9297e14750a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72fdedda938d2939f962c370a2bfbf98f0297699f79334ffdb62732d7ab7b8506d4c198ec2701950386d7a8a1cb36ea06e9ad8f4a53117bb42dc08e2855b1208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8d1ec81d96b684a7d1df9187ab9c7ca9be7c7848dc9a2df2b63c32a32f7aca04052b6cb427ba36e12e0f939d13d582c73ee1f83487e42b61358987688b2ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0cdb99e0115854642e230dd6008ba6179cad9320634b536e43b9f055295e9485f4e2040372e13c98106ed4e63a63fbcd0630590a270c87e4c7340ed44a79af7"} 19:23:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:23:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:23:01 INFO - Contents: 19:23:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c3d33b3d7ab38fb58888584d40cbcaf1d946f7dd47635aa98220973969b9e64950c394c87bac5b1671fa7b86e7a146079de86b500ab53ec5303d9297e14750a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72fdedda938d2939f962c370a2bfbf98f0297699f79334ffdb62732d7ab7b8506d4c198ec2701950386d7a8a1cb36ea06e9ad8f4a53117bb42dc08e2855b1208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8d1ec81d96b684a7d1df9187ab9c7ca9be7c7848dc9a2df2b63c32a32f7aca04052b6cb427ba36e12e0f939d13d582c73ee1f83487e42b61358987688b2ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0cdb99e0115854642e230dd6008ba6179cad9320634b536e43b9f055295e9485f4e2040372e13c98106ed4e63a63fbcd0630590a270c87e4c7340ed44a79af7"} 19:23:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:23:01 INFO - Copying logs to upload dir... 19:23:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:23:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.085601 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-02 19:23:01.179085) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 19:23:01.183062) ========= 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.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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/2c3d33b3d7ab38fb58888584d40cbcaf1d946f7dd47635aa98220973969b9e64950c394c87bac5b1671fa7b86e7a146079de86b500ab53ec5303d9297e14750a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72fdedda938d2939f962c370a2bfbf98f0297699f79334ffdb62732d7ab7b8506d4c198ec2701950386d7a8a1cb36ea06e9ad8f4a53117bb42dc08e2855b1208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8d1ec81d96b684a7d1df9187ab9c7ca9be7c7848dc9a2df2b63c32a32f7aca04052b6cb427ba36e12e0f939d13d582c73ee1f83487e42b61358987688b2ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0cdb99e0115854642e230dd6008ba6179cad9320634b536e43b9f055295e9485f4e2040372e13c98106ed4e63a63fbcd0630590a270c87e4c7340ed44a79af7"} build_url:https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011041 build_url: 'https://queue.taskcluster.net/v1/task/VCOtdwarSxewpzVbgvgx7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c3d33b3d7ab38fb58888584d40cbcaf1d946f7dd47635aa98220973969b9e64950c394c87bac5b1671fa7b86e7a146079de86b500ab53ec5303d9297e14750a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72fdedda938d2939f962c370a2bfbf98f0297699f79334ffdb62732d7ab7b8506d4c198ec2701950386d7a8a1cb36ea06e9ad8f4a53117bb42dc08e2855b1208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8d1ec81d96b684a7d1df9187ab9c7ca9be7c7848dc9a2df2b63c32a32f7aca04052b6cb427ba36e12e0f939d13d582c73ee1f83487e42b61358987688b2ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0cdb99e0115854642e230dd6008ba6179cad9320634b536e43b9f055295e9485f4e2040372e13c98106ed4e63a63fbcd0630590a270c87e4c7340ed44a79af7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 19:23:01.217371) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:23:01.217684) ========= 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.rMrFnKWwCx/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.99nHxsZA2q/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005249 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 19:23:01.265644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 19:23:01.265969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 19:23:01.266331) ========= ========= Total master_lag: 0.61 =========