builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1486555040.7 results: success (0) buildid: 20170208023106 builduid: 970ad77949ab4bba8497648a9ff5f49c revision: 3a95aa4246653a7863914ffec032897d13359fb0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.703471) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.703908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.704183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.730058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.730481) ========= 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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-08 03:57:20-- 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% 168M=0s 2017-02-08 03:57:20 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130941 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.885436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.885783) ========= 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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.025596 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:20.934373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 03:57:20.934721) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3a95aa4246653a7863914ffec032897d13359fb0 --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 3a95aa4246653a7863914ffec032897d13359fb0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-08 03:57:21,041 truncating revision to first 12 chars 2017-02-08 03:57:21,041 Setting DEBUG logging. 2017-02-08 03:57:21,041 attempt 1/10 2017-02-08 03:57:21,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3a95aa424665?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 03:57:22,020 unpacking tar archive at: mozilla-central-3a95aa424665/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 03:57:22.213588) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:22.213905) ========= script_repo_revision: 3a95aa4246653a7863914ffec032897d13359fb0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:22.214340) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:22.214602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 03:57:22.234339) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-08 03:57:22.234604) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:57:22 INFO - MultiFileLogger online at 20170208 03:57:22 in /builds/slave/test 03:57:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:57:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:57:22 INFO - {'append_to_log': False, 03:57:22 INFO - 'base_work_dir': '/builds/slave/test', 03:57:22 INFO - 'blob_upload_branch': 'Firefox', 03:57:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:57:22 INFO - 'branch': 'Firefox', 03:57:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:57:22 INFO - 'config_files': ('talos/mac_config.py',), 03:57:22 INFO - 'default_actions': ('clobber', 03:57:22 INFO - 'read-buildbot-config', 03:57:22 INFO - 'download-and-extract', 03:57:22 INFO - 'populate-webroot', 03:57:22 INFO - 'create-virtualenv', 03:57:22 INFO - 'install', 03:57:22 INFO - 'run-tests'), 03:57:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:57:22 INFO - 'download_minidump_stackwalk': True, 03:57:22 INFO - 'download_symbols': 'ondemand', 03:57:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:57:22 INFO - 'tooltool.py': '/tools/tooltool.py', 03:57:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:57:22 INFO - '/tools/misc-python/virtualenv.py')}, 03:57:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:57:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:57:22 INFO - 'installer_path': 'installer.exe', 03:57:22 INFO - 'log_level': 'info', 03:57:22 INFO - 'log_name': 'talos', 03:57:22 INFO - 'log_to_console': True, 03:57:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:57:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:57:22 INFO - 'opt_config_files': (), 03:57:22 INFO - 'pip_index': False, 03:57:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:22 INFO - 'cmd': ('bash', 03:57:22 INFO - '-c', 03:57:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:22 INFO - 'enabled': True, 03:57:22 INFO - 'halt_on_failure': False, 03:57:22 INFO - 'name': 'check_screen_resolution'},), 03:57:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:22 INFO - 'cmd': ('bash', 03:57:22 INFO - '-c', 03:57:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:22 INFO - 'enabled': True, 03:57:22 INFO - 'halt_on_failure': False, 03:57:22 INFO - 'name': 'check_screen_resolution'},), 03:57:22 INFO - 'run_cmd_checks_enabled': True, 03:57:22 INFO - 'sps_profile': False, 03:57:22 INFO - 'sps_profile_interval': 0, 03:57:22 INFO - 'suite': 'dromaeojs', 03:57:22 INFO - 'system_bits': '32', 03:57:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:57:22 INFO - 'title': 't-yosemite-r7-0300', 03:57:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:57:22 INFO - 'use_talos_json': True, 03:57:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:57:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:57:22 INFO - 'work_dir': 'build'} 03:57:22 INFO - [mozharness: 2017-02-08 11:57:22.451955Z] Running clobber step. 03:57:22 INFO - Running pre-action listener: _resource_record_pre_action 03:57:22 INFO - Running main action method: clobber 03:57:22 INFO - rmtree: /builds/slave/test/build 03:57:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:57:24 INFO - Running post-action listener: _resource_record_post_action 03:57:24 INFO - [mozharness: 2017-02-08 11:57:24.880893Z] Finished clobber step (success) 03:57:24 INFO - [mozharness: 2017-02-08 11:57:24.881033Z] Running read-buildbot-config step. 03:57:24 INFO - Running pre-action listener: _resource_record_pre_action 03:57:24 INFO - Running main action method: read_buildbot_config 03:57:24 INFO - Using buildbot properties: 03:57:24 INFO - { 03:57:24 INFO - "project": "", 03:57:24 INFO - "product": "firefox", 03:57:24 INFO - "script_repo_revision": "production", 03:57:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:57:24 INFO - "repository": "", 03:57:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:57:24 INFO - "buildid": "20170208023106", 03:57:24 INFO - "pgo_build": "False", 03:57:24 INFO - "basedir": "/builds/slave/test", 03:57:24 INFO - "buildnumber": 30, 03:57:24 INFO - "slavename": "t-yosemite-r7-0300", 03:57:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:57:24 INFO - "platform": "yosemite_r7", 03:57:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:57:24 INFO - "branch": "mozilla-central", 03:57:24 INFO - "repo_path": "mozilla-central", 03:57:24 INFO - "revision": "3a95aa4246653a7863914ffec032897d13359fb0", 03:57:24 INFO - "stage_platform": "macosx64", 03:57:24 INFO - "builduid": "970ad77949ab4bba8497648a9ff5f49c", 03:57:24 INFO - "slavebuilddir": "test" 03:57:24 INFO - } 03:57:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 03:57:24 INFO - Running post-action listener: _resource_record_post_action 03:57:24 INFO - [mozharness: 2017-02-08 11:57:24.884172Z] Finished read-buildbot-config step (success) 03:57:24 INFO - [mozharness: 2017-02-08 11:57:24.884292Z] Running download-and-extract step. 03:57:24 INFO - Running pre-action listener: _resource_record_pre_action 03:57:24 INFO - Running main action method: download_and_extract 03:57:24 INFO - mkdir: /builds/slave/test/build/tests 03:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:24 INFO - trying https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 03:57:24 INFO - Downloading https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:57:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 03:57:26 INFO - Downloaded 1199 bytes. 03:57:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:57:26 INFO - Using the following test package requirements: 03:57:26 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:57:26 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:57:26 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:57:26 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'jsshell-mac.zip'], 03:57:26 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:57:26 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:57:26 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:57:26 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:57:26 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:57:26 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:57:26 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:57:26 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:57:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:57:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:57:26 INFO - Fetch https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:57:27 INFO - Expected file size: 34938678 03:57:27 INFO - Obtained file size: 34938678 03:57:34 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:57:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:57:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:57:34 INFO - Fetch https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:57:36 INFO - Expected file size: 34938678 03:57:36 INFO - Obtained file size: 34938678 03:57:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:57:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:57:42 INFO - Fetch https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:57:43 INFO - Expected file size: 14403454 03:57:43 INFO - Obtained file size: 14403454 03:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:44 INFO - trying https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:57:44 INFO - Downloading https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:57:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 03:57:46 INFO - Downloaded 70369786 bytes. 03:57:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:57:46 INFO - mkdir: /builds/slave/test/properties 03:57:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:57:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:57:46 INFO - Contents: 03:57:46 INFO - build_url:https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:57:46 INFO - Running post-action listener: _resource_record_post_action 03:57:46 INFO - Running post-action listener: set_extra_try_arguments 03:57:46 INFO - [mozharness: 2017-02-08 11:57:46.714151Z] Finished download-and-extract step (success) 03:57:46 INFO - [mozharness: 2017-02-08 11:57:46.714313Z] Running populate-webroot step. 03:57:46 INFO - Running pre-action listener: _resource_record_pre_action 03:57:46 INFO - Running main action method: populate_webroot 03:57:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:57:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:57:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:57:46 INFO - '--tppagecycles', 03:57:46 INFO - '7'], 03:57:46 INFO - 'tests': ['tsvgm']}}, 03:57:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:57:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:57:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:57:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:57:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['damp', 'tps']}, 03:57:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'tests': ['damp', 'tps']}, 03:57:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['dromaeo_dom']}, 03:57:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:57:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['basic_compositor_video']}, 03:57:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:57:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['a11yr', 03:57:46 INFO - 'ts_paint', 03:57:46 INFO - 'tpaint', 03:57:46 INFO - 'sessionrestore', 03:57:46 INFO - 'sessionrestore_no_auto_restore', 03:57:46 INFO - 'tabpaint']}, 03:57:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['tsvgx', 03:57:46 INFO - 'tsvgr_opacity', 03:57:46 INFO - 'tart', 03:57:46 INFO - 'tscrollx', 03:57:46 INFO - 'cart', 03:57:46 INFO - 'tsvg_static']}, 03:57:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:57:46 INFO - 'tsvgr_opacity', 03:57:46 INFO - 'tart', 03:57:46 INFO - 'tscrollx', 03:57:46 INFO - 'cart', 03:57:46 INFO - 'tsvg_static']}, 03:57:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'talos_options': ['--disable-e10s'], 03:57:46 INFO - 'tests': ['tp5o']}, 03:57:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:57:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'talos_options': ['--disable-e10s', 03:57:46 INFO - '--xperf_path', 03:57:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:46 INFO - 'tests': ['tp5n']}, 03:57:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:57:46 INFO - 'talos_options': ['--xperf_path', 03:57:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:46 INFO - 'tests': ['tp5n']}}, 03:57:46 INFO - 'talos.zip': {'path': '', 03:57:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:57:46 INFO - Running post-action listener: _resource_record_post_action 03:57:46 INFO - [mozharness: 2017-02-08 11:57:46.733561Z] Finished populate-webroot step (success) 03:57:46 INFO - [mozharness: 2017-02-08 11:57:46.733671Z] Running create-virtualenv step. 03:57:46 INFO - Running pre-action listener: _resource_record_pre_action 03:57:46 INFO - Running main action method: create_virtualenv 03:57:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:57:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:57:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:57:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:57:46 INFO - Using real prefix '/tools/python27' 03:57:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:57:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:57:50 INFO - Installing pip.................done. 03:57:50 INFO - Return code: 0 03:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:57:50 INFO - Reading from file tmpfile_stdout 03:57:50 INFO - Output received: 03:57:50 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:57:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:50 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:50 INFO - 'HOME': '/Users/cltbld', 03:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:50 INFO - 'LOGNAME': 'cltbld', 03:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:50 INFO - 'MOZ_NO_REMOTE': '1', 03:57:50 INFO - 'NO_EM_RESTART': '1', 03:57:50 INFO - 'PAGER': '/bin/cat', 03:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:50 INFO - 'PWD': '/builds/slave/test', 03:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:50 INFO - 'SHELL': '/bin/bash', 03:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:57:50 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:50 INFO - 'USER': 'cltbld', 03:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:50 INFO - 'XPC_FLAGS': '0x0', 03:57:50 INFO - 'XPC_SERVICE_NAME': '0', 03:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:57:50 INFO - Cleaning up... 03:57:51 INFO - Return code: 0 03:57:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:51 INFO - 'HOME': '/Users/cltbld', 03:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:51 INFO - 'LOGNAME': 'cltbld', 03:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:51 INFO - 'MOZ_NO_REMOTE': '1', 03:57:51 INFO - 'NO_EM_RESTART': '1', 03:57:51 INFO - 'PAGER': '/bin/cat', 03:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:51 INFO - 'PWD': '/builds/slave/test', 03:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:51 INFO - 'SHELL': '/bin/bash', 03:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:57:51 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:51 INFO - 'USER': 'cltbld', 03:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:51 INFO - 'XPC_FLAGS': '0x0', 03:57:51 INFO - 'XPC_SERVICE_NAME': '0', 03:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:51 INFO - Downloading/unpacking psutil>=3.1.1 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:57:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:54 INFO - Installing collected packages: psutil 03:57:54 INFO - Running setup.py install for psutil 03:57:54 INFO - building 'psutil._psutil_osx' extension 03:57:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:57:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:57:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:57:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:57:55 INFO - building 'psutil._psutil_posix' extension 03:57:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:57:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:57:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:57:55 INFO - ^ 03:57:55 INFO - 1 warning generated. 03:57:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:57:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:55 INFO - Successfully installed psutil 03:57:55 INFO - Cleaning up... 03:57:56 INFO - Return code: 0 03:57:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:57: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 mozsystemmonitor==0.3 03:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:56 INFO - 'HOME': '/Users/cltbld', 03:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:56 INFO - 'LOGNAME': 'cltbld', 03:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:56 INFO - 'MOZ_NO_REMOTE': '1', 03:57:56 INFO - 'NO_EM_RESTART': '1', 03:57:56 INFO - 'PAGER': '/bin/cat', 03:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:56 INFO - 'PWD': '/builds/slave/test', 03:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:56 INFO - 'SHELL': '/bin/bash', 03:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:57:56 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:56 INFO - 'USER': 'cltbld', 03:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:56 INFO - 'XPC_FLAGS': '0x0', 03:57:56 INFO - 'XPC_SERVICE_NAME': '0', 03:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:57:59 INFO - Installing collected packages: mozsystemmonitor 03:57:59 INFO - Running setup.py install for mozsystemmonitor 03:57:59 INFO - Successfully installed mozsystemmonitor 03:57:59 INFO - Cleaning up... 03:57:59 INFO - Return code: 0 03:57:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:59 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:59 INFO - 'HOME': '/Users/cltbld', 03:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:59 INFO - 'LOGNAME': 'cltbld', 03:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:59 INFO - 'MOZ_NO_REMOTE': '1', 03:57:59 INFO - 'NO_EM_RESTART': '1', 03:57:59 INFO - 'PAGER': '/bin/cat', 03:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:59 INFO - 'PWD': '/builds/slave/test', 03:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:59 INFO - 'SHELL': '/bin/bash', 03:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:57:59 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:59 INFO - 'USER': 'cltbld', 03:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:59 INFO - 'XPC_FLAGS': '0x0', 03:57:59 INFO - 'XPC_SERVICE_NAME': '0', 03:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:59 INFO - Downloading/unpacking jsonschema==2.5.1 03:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:58:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:02 INFO - Downloading functools32-3.2.3-2.tar.gz 03:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:58:02 INFO - warning: no files found matching '*.txt' 03:58:02 INFO - no previously-included directories found matching 'build' 03:58:02 INFO - no previously-included directories found matching 'dist' 03:58:02 INFO - no previously-included directories found matching '.git*' 03:58:02 INFO - Installing collected packages: jsonschema, functools32 03:58:02 INFO - Running setup.py install for functools32 03:58:02 INFO - warning: no files found matching '*.txt' 03:58:02 INFO - no previously-included directories found matching 'build' 03:58:02 INFO - no previously-included directories found matching 'dist' 03:58:02 INFO - no previously-included directories found matching '.git*' 03:58:02 INFO - Successfully installed jsonschema functools32 03:58:02 INFO - Cleaning up... 03:58:02 INFO - Return code: 0 03:58:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:02 INFO - 'HOME': '/Users/cltbld', 03:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:02 INFO - 'LOGNAME': 'cltbld', 03:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:02 INFO - 'MOZ_NO_REMOTE': '1', 03:58:02 INFO - 'NO_EM_RESTART': '1', 03:58:02 INFO - 'PAGER': '/bin/cat', 03:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:02 INFO - 'PWD': '/builds/slave/test', 03:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:02 INFO - 'SHELL': '/bin/bash', 03:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:02 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:02 INFO - 'USER': 'cltbld', 03:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:02 INFO - 'XPC_FLAGS': '0x0', 03:58:02 INFO - 'XPC_SERVICE_NAME': '0', 03:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:58:03 INFO - Cleaning up... 03:58:03 INFO - Return code: 0 03:58:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:03 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:03 INFO - 'HOME': '/Users/cltbld', 03:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:03 INFO - 'LOGNAME': 'cltbld', 03:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:03 INFO - 'MOZ_NO_REMOTE': '1', 03:58:03 INFO - 'NO_EM_RESTART': '1', 03:58:03 INFO - 'PAGER': '/bin/cat', 03:58:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:03 INFO - 'PWD': '/builds/slave/test', 03:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:03 INFO - 'SHELL': '/bin/bash', 03:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:03 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:03 INFO - 'USER': 'cltbld', 03:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:03 INFO - 'XPC_FLAGS': '0x0', 03:58:03 INFO - 'XPC_SERVICE_NAME': '0', 03:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:06 INFO - Downloading blobuploader-1.2.4.tar.gz 03:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:58:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:58:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:07 INFO - Downloading docopt-0.6.1.tar.gz 03:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:58:07 INFO - Installing collected packages: blobuploader, requests, docopt 03:58:07 INFO - Running setup.py install for blobuploader 03:58:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:58:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:58:07 INFO - Running setup.py install for requests 03:58:08 INFO - Running setup.py install for docopt 03:58:08 INFO - Successfully installed blobuploader requests docopt 03:58:08 INFO - Cleaning up... 03:58:08 INFO - Return code: 0 03:58:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:08 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:08 INFO - 'HOME': '/Users/cltbld', 03:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:08 INFO - 'LOGNAME': 'cltbld', 03:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:08 INFO - 'MOZ_NO_REMOTE': '1', 03:58:08 INFO - 'NO_EM_RESTART': '1', 03:58:08 INFO - 'PAGER': '/bin/cat', 03:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:08 INFO - 'PWD': '/builds/slave/test', 03:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:08 INFO - 'SHELL': '/bin/bash', 03:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:08 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:08 INFO - 'USER': 'cltbld', 03:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:08 INFO - 'XPC_FLAGS': '0x0', 03:58:08 INFO - 'XPC_SERVICE_NAME': '0', 03:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-B_dkeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-QpT_LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-DBtDaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-GrXOkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:08 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-JePKG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-06epHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Q_adQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PCzbzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-wXSzzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-jilgUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-V_muTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-JN1oIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-xSd9DE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-QpOqXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:09 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-tUFK7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:10 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_2HK6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:10 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-1Hzvfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:58:10 INFO - Running setup.py install for manifestparser 03:58:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:58:10 INFO - Running setup.py install for mozcrash 03:58:10 INFO - Running setup.py install for mozdebug 03:58:10 INFO - Running setup.py install for mozdevice 03:58:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:58:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:58:10 INFO - Running setup.py install for mozfile 03:58:11 INFO - Running setup.py install for mozhttpd 03:58:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Running setup.py install for mozinfo 03:58:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Running setup.py install for mozInstall 03:58:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Running setup.py install for mozleak 03:58:11 INFO - Running setup.py install for mozlog 03:58:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Running setup.py install for moznetwork 03:58:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:58:11 INFO - Running setup.py install for mozprocess 03:58:12 INFO - Running setup.py install for mozprofile 03:58:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:58:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:58:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:58:12 INFO - Running setup.py install for mozrunner 03:58:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:58:12 INFO - Running setup.py install for mozscreenshot 03:58:12 INFO - Running setup.py install for moztest 03:58:12 INFO - Running setup.py install for mozversion 03:58:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:58:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:58:12 INFO - Cleaning up... 03:58:12 INFO - Return code: 0 03:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:12 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:12 INFO - 'HOME': '/Users/cltbld', 03:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:12 INFO - 'LOGNAME': 'cltbld', 03:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:12 INFO - 'MOZ_NO_REMOTE': '1', 03:58:12 INFO - 'NO_EM_RESTART': '1', 03:58:12 INFO - 'PAGER': '/bin/cat', 03:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:12 INFO - 'PWD': '/builds/slave/test', 03:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:12 INFO - 'SHELL': '/bin/bash', 03:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:12 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:12 INFO - 'USER': 'cltbld', 03:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:12 INFO - 'XPC_FLAGS': '0x0', 03:58:12 INFO - 'XPC_SERVICE_NAME': '0', 03:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PfVbmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-m8QSM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-d7AKen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-l9BXba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-MMQCko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-SIA9Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-27wI7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:13 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-HOtzXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-A4TFdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-e4pEdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sVXPuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_2wuzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-DSTXqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-G5GqII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-WlMTQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-kBVr6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:14 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)) 03:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:14 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-00_WxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:17 INFO - Downloading blessings-1.6.tar.gz 03:58:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:58:17 INFO - Installing collected packages: blessings 03:58:17 INFO - Running setup.py install for blessings 03:58:17 INFO - Successfully installed blessings 03:58:17 INFO - Cleaning up... 03:58:18 INFO - Return code: 0 03:58:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:58:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:18 INFO - Reading from file tmpfile_stdout 03:58:18 INFO - Current package versions: 03:58:18 INFO - blessings == 1.6 03:58:18 INFO - blobuploader == 1.2.4 03:58:18 INFO - docopt == 0.6.1 03:58:18 INFO - functools32 == 3.2.3-2 03:58:18 INFO - jsonschema == 2.5.1 03:58:18 INFO - manifestparser == 1.1 03:58:18 INFO - mozInstall == 1.12 03:58:18 INFO - mozcrash == 1.0 03:58:18 INFO - mozdebug == 0.1 03:58:18 INFO - mozdevice == 0.49 03:58:18 INFO - mozfile == 1.2 03:58:18 INFO - mozhttpd == 0.7 03:58:18 INFO - mozinfo == 0.9 03:58:18 INFO - mozleak == 0.1 03:58:18 INFO - mozlog == 3.4 03:58:18 INFO - moznetwork == 0.27 03:58:18 INFO - mozprocess == 0.24 03:58:18 INFO - mozprofile == 0.28 03:58:18 INFO - mozrunner == 6.13 03:58:18 INFO - mozscreenshot == 0.1 03:58:18 INFO - mozsystemmonitor == 0.3 03:58:18 INFO - moztest == 0.8 03:58:18 INFO - mozversion == 1.4 03:58:18 INFO - psutil == 3.1.1 03:58:18 INFO - requests == 1.2.3 03:58:18 INFO - wsgiref == 0.1.2 03:58:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:19 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:19 INFO - 'HOME': '/Users/cltbld', 03:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:19 INFO - 'LOGNAME': 'cltbld', 03:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:19 INFO - 'MOZ_NO_REMOTE': '1', 03:58:19 INFO - 'NO_EM_RESTART': '1', 03:58:19 INFO - 'PAGER': '/bin/cat', 03:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:19 INFO - 'PWD': '/builds/slave/test', 03:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:19 INFO - 'SHELL': '/bin/bash', 03:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:19 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:19 INFO - 'USER': 'cltbld', 03:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:19 INFO - 'XPC_FLAGS': '0x0', 03:58:19 INFO - 'XPC_SERVICE_NAME': '0', 03:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:58:19 INFO - Cleaning up... 03:58:19 INFO - Return code: 0 03:58:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:19 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 0x103fd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fb24db7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, '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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:19 INFO - 'HOME': '/Users/cltbld', 03:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:19 INFO - 'LOGNAME': 'cltbld', 03:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:19 INFO - 'MOZ_NO_REMOTE': '1', 03:58:19 INFO - 'NO_EM_RESTART': '1', 03:58:19 INFO - 'PAGER': '/bin/cat', 03:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:19 INFO - 'PWD': '/builds/slave/test', 03:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:19 INFO - 'SHELL': '/bin/bash', 03:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:19 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:19 INFO - 'USER': 'cltbld', 03:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:19 INFO - 'XPC_FLAGS': '0x0', 03:58:19 INFO - 'XPC_SERVICE_NAME': '0', 03:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:58:19 INFO - Cleaning up... 03:58:20 INFO - Return code: 0 03:58:20 INFO - Running post-action listener: _resource_record_post_action 03:58:20 INFO - Running post-action listener: _start_resource_monitoring 03:58:20 INFO - Starting resource monitoring. 03:58:20 INFO - [mozharness: 2017-02-08 11:58:20.043014Z] Finished create-virtualenv step (success) 03:58:20 INFO - [mozharness: 2017-02-08 11:58:20.043956Z] Running install step. 03:58:20 INFO - Running pre-action listener: _resource_record_pre_action 03:58:20 INFO - Running main action method: install 03:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:20 INFO - Reading from file tmpfile_stdout 03:58:20 INFO - Detecting whether we're running mozinstall >=1.0... 03:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:58:20 INFO - Reading from file tmpfile_stdout 03:58:20 INFO - Output received: 03:58:20 INFO - Usage: mozinstall [options] installer 03:58:20 INFO - Options: 03:58:20 INFO - -h, --help show this help message and exit 03:58:20 INFO - -d DEST, --destination=DEST 03:58:20 INFO - Directory to install application into. [default: 03:58:20 INFO - "/builds/slave/test"] 03:58:20 INFO - --app=APP Application being installed. [default: firefox] 03:58:20 INFO - mkdir: /builds/slave/test/build/application 03:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:58:42 INFO - Reading from file tmpfile_stdout 03:58:42 INFO - Output received: 03:58:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:58:42 INFO - Running post-action listener: _resource_record_post_action 03:58:42 INFO - [mozharness: 2017-02-08 11:58:42.296888Z] Finished install step (success) 03:58:42 INFO - [mozharness: 2017-02-08 11:58:42.297034Z] Running run-tests step. 03:58:42 INFO - Running pre-action listener: _resource_record_pre_action 03:58:42 INFO - Running main action method: run_tests 03:58:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:42 INFO - 2017-02-08 03:58:42.311 screenresolution[1632:7330] starting screenresolution argv=screenresolution get 03:58:42 INFO - 2017-02-08 03:58:42.314 screenresolution[1632:7330] Display 0: 1600x1200x32@60 03:58:42 INFO - 2017-02-08 03:58:42.319 screenresolution[1633:7332] starting screenresolution argv=screenresolution list 03:58:42 INFO - Available Modes on Display 0 03:58:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:42 INFO - Intel Iris: 03:58:42 INFO - Chipset Model: Intel Iris 03:58:42 INFO - Type: GPU 03:58:42 INFO - Bus: Built-In 03:58:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:42 INFO - Vendor: Intel (0x8086) 03:58:42 INFO - Device ID: 0x0a2e 03:58:42 INFO - Revision ID: 0x0009 03:58:42 INFO - Displays: 03:58:42 INFO - Display: 03:58:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:42 INFO - Display Serial Number: 1600x1200 60 03:58:42 INFO - Main Display: Yes 03:58:42 INFO - Mirror: Off 03:58:42 INFO - Online: Yes 03:58:42 INFO - Rotation: Supported 03:58:42 INFO - Return code: 0 03:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:42 INFO - Python 2.7.3 03:58:42 INFO - Return code: 0 03:58:42 INFO - grabbing minidump binary from tooltool 03:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:42 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 0x103dafd30>, '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 0x103cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103da4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:58:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:58:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:58:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:42 INFO - Return code: 0 03:58:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:58:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:58:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nLoyJwxWsn/Render', 03:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:42 INFO - 'HOME': '/Users/cltbld', 03:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:42 INFO - 'LOGNAME': 'cltbld', 03:58:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:42 INFO - 'MOZ_NO_REMOTE': '1', 03:58:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:42 INFO - 'NO_EM_RESTART': '1', 03:58:42 INFO - 'PAGER': '/bin/cat', 03:58:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:42 INFO - 'PWD': '/builds/slave/test', 03:58:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:42 INFO - 'SHELL': '/bin/bash', 03:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8EtFsbj6Ua/Listeners', 03:58:42 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 03:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:42 INFO - 'USER': 'cltbld', 03:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:42 INFO - 'XPC_FLAGS': '0x0', 03:58:42 INFO - 'XPC_SERVICE_NAME': '0', 03:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:58:42 INFO - mozversion application_buildid: 20170208023106 03:58:42 INFO - mozversion application_changeset: 3a95aa4246653a7863914ffec032897d13359fb0 03:58:42 INFO - mozversion application_display_name: Nightly 03:58:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:42 INFO - mozversion application_name: Firefox 03:58:42 INFO - mozversion application_remotingname: firefox 03:58:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:58:42 INFO - mozversion application_vendor: Mozilla 03:58:42 INFO - mozversion application_version: 54.0a1 03:58:42 INFO - mozversion platform_buildid: 20170208023106 03:58:42 INFO - mozversion platform_changeset: 3a95aa4246653a7863914ffec032897d13359fb0 03:58:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:58:42 INFO - mozversion platform_version: 54.0a1 03:58:42 INFO - using testdate: 1486555122 03:58:42 INFO - actual date: 1486555122 03:58:42 INFO - starting webserver on 'localhost:49240' 03:58:42 INFO - SUITE-START | Running 2 tests 03:58:42 INFO - TEST-START | dromaeo_css 03:58:42 INFO - operating with platform_type : mac_ 03:58:42 INFO - Initialising browser for dromaeo_css test... 03:58:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp7AI_Pd/profile http://localhost:49240/getInfo.html) 03:58:45 INFO - PROCESS | 1643 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:58:45 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 03:58:45 INFO - PROCESS | 1643 | colorDepth:24 03:58:45 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 03:58:45 INFO - PROCESS | 1643 | __metrics 03:58:45 INFO - PROCESS | 1643 | 1486555125379 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 03:58:45 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:58:45 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:58:46 INFO - TEST-INFO | 1643: exit 0 03:58:46 INFO - Browser initialized. 03:58:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:58:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp7AI_Pd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:58 INFO - PROCESS | 1645 | RSS: Main: 236937216 03:58:58 INFO - PROCESS | 1645 | 04:02:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:02:03 INFO - PROCESS | 1645 | RSS: Main: 395345920 04:02:03 INFO - PROCESS | 1645 | 04:03:47 INFO - PROCESS | 1645 | ************************* 04:03:47 INFO - PROCESS | 1645 | A coding exception was thrown and uncaught in a Task. 04:03:47 INFO - PROCESS | 1645 | 04:03:47 ERROR - PROCESS | 1645 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:03:47 INFO - PROCESS | 1645 | Full stack: 04:03:47 INFO - PROCESS | 1645 | ************************* 04:05:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:05:07 INFO - PROCESS | 1645 | RSS: Main: 374591488 04:05:07 INFO - PROCESS | 1645 | 04:08:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:08:10 INFO - PROCESS | 1645 | RSS: Main: 363773952 04:08:10 INFO - PROCESS | 1645 | 04:11:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:11:13 INFO - PROCESS | 1645 | RSS: Main: 349458432 04:11:13 INFO - PROCESS | 1645 | 04:14:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 04:14:17 INFO - PROCESS | 1645 | RSS: Main: 340320256 04:14:17 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 04:17:20 INFO - PROCESS | 1645 | RSS: Main: 598458368 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | __start_tp_report 04:17:20 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 04:17:20 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 04:17:20 INFO - PROCESS | 1645 | |i|pagename|runs| 04:17:20 INFO - PROCESS | 1645 | |0;dojo.html;3261.2499125201202;3268.8692452301907;3272.9018129456117;3281.917952051197;3315.5026745988116;5364.061289274371;5463.726813659321;5486.615936884415;5537.612367134302;5538.889222215558;5443.8094666686675;5486.506214440699;5531.9723401383035;5550.111982082867;5565.276514053177;3992.0419099416076;4740.976295118519;4985.20236762119;5036.244563315514;5117.641765076446;5155.3040339554245;5187.584993200554;5202.06362854686;5209.479052094779;5218.217267409881;4966.602671786249;4997.375328083989;5002.049610573979;5162.328897243335;5172.40517340505;5000.249962505617;5014.423341315723;5016.799328026875;5061.721605311706;5124.897502049938;4984.501549845023;5006.5744411725045;5017.573506251973;5030.0694371541385;5074.695518268915;5494.917576236359;5504.779808807642;5509.035918714209;5520.779168833242;5525.696086715229;5787.450192231731;5849.912251316234;5850.414958504116;5886.175935369051;5941.702914854283;6094.9390506094815;6112.390397230779;6116.579253789288;6128.533679014817;6136.570440069197;5564.8330550083965;5565.109582466826;5575.60970732053;5579.2746942897165;5585.748641311069;6045.727942242522;6113.57204995646;6125.601835880653;6209.161763162006;6240.032794916795;5753;5793.102069179284;5805.071188609802;5809.2738407699035;5812.59311848175;5920.911186332209;5940.821775346746;5948.048312270016;6004.189434426384;6095.664738439428;6020.307664618537;6055.636661800306;6057.970145075347;6076.605020673642;6080.118382834465;6094.634321940698;6098.664573448503;6103.23709536308;6107.45032947028;6114.6636934969;4717.386739723814;4877.487863774323;4882.804687812519;4888;4890;4268.423762791983;4320.071184695243;4351.282038463618;4376.824927002885;4380.67144964129;5591.3569939456665;5591.888162236733;5595.720213989365;5596.496315331639;5596.748146333425;4810.5670489656095;5961.135635332853;5966.731497082642;5970.731317090741;5979;5575.080111781592;5586.720663966867;5588.413216612278;5593.328800543879;5600.103983362643;4291.506476755213;5204.245384419238;5243.9213411798855;5249.2126181073145;5251.632385732962;5653.784436346123;5732.541396688255;6174.753009879554;6213.161223234805;6246.187995560548;6151.384861513813;6154.292256390572;6157.9384206157365;6158.938410615837;6159.5688301818445;6327.3988971047675;6328.46208072319;6340.936590634219;6347.428731414195;6357.80926572185;5787.508061814625;5826.067829147316;5831.300243970751;5923;5955.374685657944;6102.79223100439;6151.446369826737;6153.538484613582;6161.537884658579;6165.106059621419;5911.556633252438;5925.674087925206;5928.940710593011;5937.435943585352;5952.5237980960555;5609.5512359012;5664.688442135557;5676.318841739017;5898.587098903207;5902.675352855463;5261.73691315425;5705.372409035086;5775.104858746901;5798.797042103675;5818.912716309174;5715.771369145354;5856.092305692625;5892.381299963442;5898.380670029585;5915.467607915309;5556.138798486241;5719.799807006735;5764.337101233412;5770.682612456355;5786.623869448473 04:17:20 INFO - PROCESS | 1645 | |1;ext.html;448.67246909755875;450.88727818045487;499.4965154883801;513.0252520211595;514.7014731455755;26088.08691695791;37090.814545927264;39018.60981390185;39080.413793793115;40814.12265834741;39608;40147.19705605893;40229;40290.79854600723;40308;7711.845763084735;9056.275497960147;9236.538173091352;9263.722088337361;9286.257099432029;7787.805304867367;10737.624183153592;10971.561137554489;10983.560657573687;10987.450627468634;10940.671779846578;10990;11011.724706882313;11021.559137634486;11035.944820275887;7859.6463159157975;8036.156203598655;10930.890691093107;10983.286086404438;11017.83473247906;10526;10808.351498910091;10854.077403420719;10868.184886133571;10927.672169834917;36913.63086369129;37595.62404375948;37636.247275054346;38018.80990595029;38091.23817523662;3774.3394905891632;3805.899143672686;3835.5972622874474;3841.3661745811905;3966.3257246268204;28141.57787633187;28660.713392866222;28727.856360718273;29314.853425732734;29547.556786648;2938.353562216309;2938.8971386001385;2952.35048289376;2958.6301712285963;2962.4963756161505;438436;442378.78810606065;443226;443648;446617.99999999674;46758.76620616875;47926.28110578344;56050;56501.71749141228;56552.43447565554;57630.4236957625;58624;59036.11445828316;61192.38807611956;61272.387276127556;13177.341132943315;13303.667408314675;13317.467301307839;13328.00039997004;13338.93330533341;10360.637377691744;10559.472026398804;10679.83980240297;10703.78592428147;10708.571657133627;14957.775633365509;14970.775438368215;14973.850261497464;14976.925115374353;15131.243437828065;6796.320367963164;6934;6963.9651801740665;6985.615791131435;7001.579905205704;712.4763298975249;819.4755356571799;826.6566828903033;838.6980686952692;859.9570021498901;28498.002569909953;28829.56755648667;28871.278218044295;29229.123126306244;29382.11853644394;984.9015098490094;1006.2603986070379;1036.7926414717087;1045.8692663417073;1106.778644271149;14339;14754.852451475348;15041.473548425753;15118.848811511964;15158.86308526865;585462;590164;590243;591299;591646;43706.562934370886;46896.06207875823;57108;57243.42756572464;57262;1310.502009236503;1345.663584103974;1351.8715722006393;1353.769859123971;1354.3905242640653;27102.864485677444;28406.43187136287;28558.000469984283;28609.570856436752;28815;46832;47139.05721885612;47316.7634161827;47320.29019564778;47447.81380465516;503283;507082;507805;509141;509443;36322.8183859079;36693.266134676625;36698.8165059173;36818.447723283636;36876.63123368839;41640;42014.159716806105;42098.73703788891;42452.57547424486;43333.56666433295;33289;35345.29309413849;35482.46776298306;36378.90863274106;36600.81699591485;34968.82515587406;35087.29825403529;35480.29039419249;36383.81808090943;36551.63448365482;34492;35366.29267414689;35777.46333804943;35800.92597222087;35978;29769.958051468093;30424.543631845096;31240.843795780875;31254.843725781226;31517.527237091002;23293.88353058224;23844.403889902893;23941.119288988;23972.88013559991;24003.879980599984 04:17:20 INFO - PROCESS | 1645 | |2;jquery.html;335.53528363216947;359.6637144270109;374.3804004372763;388.74148691120405;401.7569370530829;14114.364853581588;14216.004879658429;15073.397064117422;19682.90158549205;20225.190992360287;20166.596668066668;20832.375028749124;21342.932853357346;21579.568408631858;21781.128754849786;5411.6200368906075;5574.386817450067;5708.315002199742;5830.854228644275;5838;5178.275041494194;5642.7178641067985;5706.714664266791;5770.682612456313;5771.826845194651;5474.753636086386;5652.265205523277;5729.424408287712;5735.455131762496;5780.53755699543;5609.747561359728;5667.3482549506925;5694.202811606378;5705.942940570582;5707.857303567403;4376.474823021258;4676.789544470507;5062.012907483042;5062.0888240116765;5069.645124841262;24894.751052489424;25474.617880731803;25859.74140258592;26167.084152054776;26310.947562097685;2672.812903096784;2828.9717102828913;2838.9716102838916;2902.7097290270804;3078.3227689908185;16503.257353419125;16980.21707720684;17093.743593846102;17165.656686866194;17834.55413614657;1914.2151717795637;1927.6170321086404;1937.7965313642005;1981.3560592807396;1990.2735501541977;113740;148218;150848;152481;153745;9681.080297371738;10168.33905796121;10367.118794902517;10367.377957322586;10658.040776330012;12832.935835320764;12862.228266304051;12902.419391127421;12918.870811291954;12936.353182340845;6029.427204415661;6181.783637572664;7322.414206863545;7516.586587737727;7603.885941710879;7254.89710507421;7286.931676274005;7289.230092564791;7440.14438339587;7444.8883266751045;3624.4563315502887;4011.1777085697945;4110.725675040687;4125.793710314472;4220.240356735756;2008.116428771336;2040.36748607932;2053.4968932611605;2054.989725051395;2066.524699319165;983.896690847465;992.5781542844262;995.8141878576599;1010.8938561450942;1031.7523794289316;19004.42986710401;21221.78778212198;21432.78567214339;21724.782752172592;21736.673949890763;916.757059379265;994.5872462927804;1030.9175265978704;1034.6430481484026;1039.6205385034407;11138.777224455627;11370.31778093317;11392.601258956063;11393.601223957121;11437.771244575062;664164;675431;681357;681499;688222;96608;150148;151899;153330.23334883476;163487.18256408643;947.3605316411532;1005.0602686488157;1024.6977141743055;1025.9487025648689;1059.7986382587283;18865.05674716231;19537.92541410236;19572;19589;19644.803551964298;54074.45925540852;57797;58337.4166258332;58430;59411.21766347017;86014.84962875978;87084;87332.1266787324;87547.56226218828;88457;8032.437729358889;8386.748397548083;8425.4944703318;8529;8599.871001934851;12075.75848483043;12270.631881043582;12602.621921342374;13319.400626971616;13361.465541378237;9499.952500237454;11072.058874995393;11495.8275625864;11526.769464610494;11535.077193824307;10938.343699378063;10963;11131.332120072822;11185.440727963407;11295.322280662858;9301.674441394518;10905.127589792955;11205;11238.269512481662;11625;7146.3568278855155;7422.517536360119;7481.850362992818;7517.548947063194;8657.870131948152;8017.559034253172;8267.793305167419;10218.540165692413;11017.83473247886;11081.335119892832 04:17:20 INFO - PROCESS | 1645 | |3;mootools.html;1305.811711342678;1418.2908545727137;1426.2726009735031;1443.7184748973955;1449.6158517992733;8036.437449378546;8440.324774018063;8448.957755211222;8480.999999999993;8485.193906578881;8083.757487275377;8350.791230219247;8457.069722330538;8537.10360412157;8542.145785421455;2256.638937769957;2293.8050265727434;2294.7934685878267;2301.746807851135;2304.06685292456;1985.7021446783026;2260.886955652211;2308.9191878284264;2317.6986991691056;2327.8836058197107;1984.0575726529928;2046.9283575074876;2208.503086805464;2280.4184932842104;2282.1327895399727;2204.272590045281;2225.432514708747;2234.4078819112947;2237.049254067015;2240.4958884251078;2206.933791986243;2257.81937445004;2267.682524446579;2268.2514770125986;2270.14869423966;8390.32877369809;8482.321414286842;8487.66049358025;8494.617742201615;8495.150484951517;5037.546620804109;5159.7678104485385;5187.636865419422;5195.376554813409;5199.974000130013;2963.9110826675233;3197.2486465680545;3232.7413806895393;3484.8431820568135;3493.458513930345;1152.9250598711058;1159.7912375772441;1178.3048001679051;1195.0499353014288;1220.6216073017304;10773.4613269337;10795.514201860935;10841.51213195416;10855.45722713869;10870;4192.119654872511;4426.048399594103;4476.597106260452;4503.211937910853;4510.797014134306;8758.255548278341;8784.209421152127;8786.86819697705;8792.648294068165;8794.692185773596;5088.211327244209;5096.413912400048;5099.64302498829;5107.489251074863;5108.770105345268;4600.102979918884;4623.214053610895;4701.529847015339;4704.435467743892;4763.404574428197;3549.254656522159;3597.892063238107;3618.588750387351;3646.1431563582537;3674.9081272967856;1108.2685427617735;1108.5067145120506;1113.6269349767917;1124.6401151631562;1124.746931940307;689.300360134464;755.8223817402906;773.3851587987564;783.3145997252404;822.7901885019281;4872.634552408584;5401.297831281908;5611.523020543218;5669.801556945569;5751.22358481608;830.9584520774058;833.1388680631727;969.9515024248759;972.7616733900239;1004.3672486333563;3708;3760.1539653577724;3790.298794722985;3830.502034735522;3870.3033453978555;9812.460314682618;9954.701358959242;9975.750606234902;10199.694009179737;10201.38791672502;9919.553620086968;9920.553575089138;9923.503824808731;10110.797784044278;10127.949360253151;7553.093628764584;7791.181925897812;7932;7951.602419878983;7952;5180.248863914787;5461.617686761889;5749.108888122348;5797.101449275369;5839.708014599338;8979.371443998858;9030.909690903007;9298.95350523243;9389.81220375575;9414.529273536433;9249.583768730557;9252.259819214582;9508.952455237679;9570.904290957002;9597.712068637951;7095.006699062239;7332.5967071811565;7340.11918569775;7349.55902645843;7357.043584334002;7813.609319534115;7851;7962.641681124479;7968.84062318762;7970.641321140447;7162.534435261691;7179.856402871809;7194;7256.346928776436;7258.564486130849;6828.5220034597105;7009;7029.543079700008;7125.3230943060325;7130.393916517154;6289.496840252677;6322.304546499973;6388.55280130405;6450.41946224842;6465.676716164079;4828.8792780180775;4895.755212239445;5002.324686167466;5023.899522009612;5182.611304152279;4989.126902791924;5020.899582008413;5086.593072554261;5113.53978141969;5119.385673719177 04:17:20 INFO - PROCESS | 1645 | |4;prototype.html;307.5432982021698;351.02601980371793;353.70112255144403;365.77139287945033;366.7322854316351;3935.783531905744;4880.072786170634;5018.146915024446;5322.574194064475;5371.973140134294;4109.671226301896;4626.676132670715;4767.308740232665;5023.547880690737;5224.6081543884175;3364.2934983653463;3997.72015958883;4005.539362973266;4018.97990510047;4025.335819589779;4052.5542190359038;4053.5338436079933;4058.776767277813;4059.776712280823;4069.125138095309;3214.5981752280964;3591.6767490925813;3957.406389041638;4028.5971402859773;4038.5759495253014;3435.6736110069505;3964.5242570891387;3965.900852478711;3983.7410568313257;3985.7807820569856;2826.812738649772;3196.824174670392;3444.2422667013466;3449.9827500862584;3451.741119416054;4627.953720462786;5105.668131571436;5110.514501122388;5121.615878809104;5144.57885506138;4179.770112643803;4361.629261512775;4505.864824055284;4558.202314594932;4571.268597024494;4634.814607415733;4976.138720235879;5315.255864179018;5541.307336582927;5543.667379957175;3224.6452890182063;3542.415501442259;3725.1245957200035;3799.6891072579915;3821.159344944108;4937.136001199811;5541.806036788693;5591.664500130005;5728.713564321767;5797.53589735901;2969.792114551961;3799.2211596622624;3804.7336686432236;3816.5992570779667;3848.461215429842;3883.6310550497656;3900.356441187229;3901.5318161820765;3902.2585708715255;3910.4720862683744;4246.278132717383;4548.931766023512;4556.453225612948;4570.314452832035;4579.473360563511;4439.311906654474;5567.693776842312;5571.498565129158;5582.860428489239;5623.718814059281;1181.8358916467444;1206.7345184059493;1239.1945235596934;1263.286243272548;1269.3653173413293;740.9851802964018;793.8213901872033;803.8593246181895;852.2542775071812;870.4298684361753;4886.992666013905;5027.723475208899;5028.497150285016;5028.522290382408;5073.097588404348;4720.905581888343;4931.13705101613;5127.512886275797;5491.919974024092;5506.871091426248;4198.6011328923705;4585.426821647294;4810.927836082462;5048.16705243638;5055.747212639427;2349.7297810751984;2457.832529548486;2592.533343998061;2876.878017573148;2912.66504351998;5090.872728181825;5261;5555.527780138653;5758.510526605285;5798.8550286242335;4738.620910327166;4766.880827979328;4860.181094964912;5468;5509.889802204014;5128.79484820603;5539.750711218005;5615.494605485526;5642.319898048394;5776.555861034742;4751.7882939850115;5293.841184764463;5411.567074634098;5511;5533.446655334435;4568.383268258743;4580.793864276182;4934.5312195341385;4942.011597680406;5114.437411884615;4823.710577365369;5175.844724658266;5579.581531385081;5742.339630942495;5757.165211044459;3115.9688403116584;3146.8985854950583;3630.5130985275173;3656.9817150914073;3664.303782281358;4370.557715953792;5068.467810879804;5231.398389185292;5523.944760552343;5673.631213971079;4519.683622146418;4560.19872150492;5546.306711661043;5586.620094842724;5723.542116630742;5004.324416203766;5059.988002399461;5292.338457692788;5395.550494001009;5611.214429979887;4737.573618374363;4810.086083644096;5128.358955130609;5439.755211015435;5520.1443776214755;4984.501549844987;5187.054854429589;5280.970210808855;5494.862628434321;5623.859403514781;5237.607179461479;5250.501202385767;5458.372287187024;5500.28496295479;5521.641093328921 04:17:20 INFO - PROCESS | 1645 | |5;yui.html;705.5519744961948;705.6013352455863;720.1430297945444;751.1540910538042;853.5348235211811;14254.287285635715;14538.055026423275;14949.850501494995;15019.924900375496;15216.163111028889;13497.190168589892;13560.525381611646;13961.860381396184;13965.930170349135;13979.301034948263;828.3994104274383;851.0510780479782;852.957352132394;853.7524118005771;855.7603870916154;829.2163905109674;850.3409857360564;853.2619284319051;863.434450434963;868.9087645797196;882.2368651116802;904.1997831918767;905.3436258712413;907.2741806554762;907.3149771922223;898.7393655839799;903.4488961733338;903.9548022598909;904.8461761500495;905.0813424374232;675.0650349266301;676.9120014398146;676.9763058292937;677.915260592426;678.6572780745691;14760.261986900612;15059.698806023818;15098.924505377512;15137.470188543348;15151.848481515153;13632.93183534086;13641;13923.651908702263;13939.581812545639;14048.227347495884;11543.01884339832;11563;11618.767624647544;11994.70013249667;12047.759044819142;3562.1272788167066;3633.8364773585254;3652.954345566491;3693.519842420468;3794.7912864792424;102928.48535757349;104319.95680043178;104545.43181852279;104600;105656.41515377199;9333.533323333942;10585.100266477435;10719.98160174782;10757.784844303227;10841.566337346576;20363.287284944956;21882.9058547072;22129.114835406726;22205.222817201644;22209.777902221093;7302.890456643155;7340.192578816433;7418.517796343221;7484.251574842581;7594.848103037908;3372.802655057468;4128.1330920506425;4149.024979129902;4153.252414565347;4160.1471698301775;3111.175538482304;3559.1635965548076;3623.0217841182734;3623.239119784822;3670;661.7022339946947;664.7374287156546;670.061913321354;680.8195828105556;681.6012632609879;649.7108786589922;653.4151934019121;670.8356452669127;679.3987321220756;721.3688022979893;9619.90380096204;9877.654282100091;9957.800843983225;10039.548220330104;10380.584776609003;560.7981126794351;567.85068515668;590.8759160576327;650.8210242183437;661.1933441201726;4373.608601463517;4834.371531700856;4909;4995.975020124876;5003.224500202475;70065;76771;91675;96291.51854240824;97697.51151244198;59124;61433;71171;85202.57398712967;91144;79280.60359698164;80028.5998570015;80301.59849200716;80404;84487;10683.626073087406;10764.40795756241;10918.399488028235;10993.780124397626;10996.450177491253;47177.528224717316;47332.52667473281;47396.2890556635;48326;49917;63542.36457635365;64231.67884160736;64734.676326618064;65189.67405162944;65734.45452091198;13606.86393136056;13917.582472525435;14112.929435352757;14158.858411415755;14293.928530357281;14210;14277.014007448208;14357.066790658573;14783.926080369529;15031.849681503045;13518.459261629425;13609.11540749848;13778;13782.655433613838;14510.201938893462;13455.192688438725;13525.837382636579;13828.446862125402;13983.860161398256;14263.786043209153;13077.738445231233;14071.718565628425;14076.929615351857;14086.366113524711;14088.506902258781;10729.999057553558;10850;11202.887971120184;11257.718557035812;11700.648980530597;8398.580070996548;9793.510324483605;10382.377057376581;10921.290116142425;11401.144914131308 04:17:20 INFO - PROCESS | 1645 | __end_tp_report 04:17:20 INFO - PROCESS | 1645 | __start_cc_report 04:17:20 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1394 04:17:20 INFO - PROCESS | 1645 | __end_cc_report 04:17:20 INFO - PROCESS | 1645 | __startTimestamp1486556240810__endTimestamp 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | ------- Summary: start ------- 04:17:20 INFO - PROCESS | 1645 | Number of tests: 6 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5549.13 Median:5705.37 stddev:633.85 (11.1%) stddev-sans-first:609.85 04:17:20 INFO - PROCESS | 1645 | Values: 3261.2 3268.9 3272.9 3281.9 3315.5 5364.1 5463.7 5486.6 5537.6 5538.9 5443.8 5486.5 5532.0 5550.1 5565.3 3992.0 4741.0 4985.2 5036.2 5117.6 5155.3 5187.6 5202.1 5209.5 5218.2 4966.6 4997.4 5002.0 5162.3 5172.4 5000.2 5014.4 5016.8 5061.7 5124.9 4984.5 5006.6 5017.6 5030.1 5074.7 5494.9 5504.8 5509.0 5520.8 5525.7 5787.5 5849.9 5850.4 5886.2 5941.7 6094.9 6112.4 6116.6 6128.5 6136.6 5564.8 5565.1 5575.6 5579.3 5585.7 6045.7 6113.6 6125.6 6209.2 6240.0 5753.0 5793.1 5805.1 5809.3 5812.6 5920.9 5940.8 5948.0 6004.2 6095.7 6020.3 6055.6 6058.0 6076.6 6080.1 6094.6 6098.7 6103.2 6107.5 6114.7 4717.4 4877.5 4882.8 4888.0 4890.0 4268.4 4320.1 4351.3 4376.8 4380.7 5591.4 5591.9 5595.7 5596.5 5596.7 4810.6 5961.1 5966.7 5970.7 5979.0 5575.1 5586.7 5588.4 5593.3 5600.1 4291.5 5204.2 5243.9 5249.2 5251.6 5653.8 5732.5 6174.8 6213.2 6246.2 6151.4 6154.3 6157.9 6158.9 6159.6 6327.4 6328.5 6340.9 6347.4 6357.8 5787.5 5826.1 5831.3 5923.0 5955.4 6102.8 6151.4 6153.5 6161.5 6165.1 5911.6 5925.7 5928.9 5937.4 5952.5 5609.6 5664.7 5676.3 5898.6 5902.7 5261.7 5705.4 5775.1 5798.8 5818.9 5715.8 5856.1 5892.4 5898.4 5915.5 5556.1 5719.8 5764.3 5770.7 5786.6 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:64290.47 Median:36467.73 stddev:137877.97 (378.1%) stddev-sans-first:138181.44 04:17:20 INFO - PROCESS | 1645 | Values: 448.7 450.9 499.5 513.0 514.7 26088.1 37090.8 39018.6 39080.4 40814.1 39608.0 40147.2 40229.0 40290.8 40308.0 7711.8 9056.3 9236.5 9263.7 9286.3 7787.8 10737.6 10971.6 10983.6 10987.5 10940.7 10990.0 11011.7 11021.6 11035.9 7859.6 8036.2 10930.9 10983.3 11017.8 10526.0 10808.4 10854.1 10868.2 10927.7 36913.6 37595.6 37636.2 38018.8 38091.2 3774.3 3805.9 3835.6 3841.4 3966.3 28141.6 28660.7 28727.9 29314.9 29547.6 2938.4 2938.9 2952.4 2958.6 2962.5 438436.0 442378.8 443226.0 443648.0 446618.0 46758.8 47926.3 56050.0 56501.7 56552.4 57630.4 58624.0 59036.1 61192.4 61272.4 13177.3 13303.7 13317.5 13328.0 13338.9 10360.6 10559.5 10679.8 10703.8 10708.6 14957.8 14970.8 14973.9 14976.9 15131.2 6796.3 6934.0 6964.0 6985.6 7001.6 712.5 819.5 826.7 838.7 860.0 28498.0 28829.6 28871.3 29229.1 29382.1 984.9 1006.3 1036.8 1045.9 1106.8 14339.0 14754.9 15041.5 15118.8 15158.9 585462.0 590164.0 590243.0 591299.0 591646.0 43706.6 46896.1 57108.0 57243.4 57262.0 1310.5 1345.7 1351.9 1353.8 1354.4 27102.9 28406.4 28558.0 28609.6 28815.0 46832.0 47139.1 47316.8 47320.3 47447.8 503283.0 507082.0 507805.0 509141.0 509443.0 36322.8 36693.3 36698.8 36818.4 36876.6 41640.0 42014.2 42098.7 42452.6 43333.6 33289.0 35345.3 35482.5 36378.9 36600.8 34968.8 35087.3 35480.3 36383.8 36551.6 34492.0 35366.3 35777.5 35800.9 35978.0 29770.0 30424.5 31240.8 31254.8 31517.5 23293.9 23844.4 23941.1 23972.9 24003.9 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:38695.67 Median:26013.41 stddev:113706.89 (437.1%) stddev-sans-first:113989.38 04:17:20 INFO - PROCESS | 1645 | Values: 335.5 359.7 374.4 388.7 401.8 14114.4 14216.0 15073.4 19682.9 20225.2 20166.6 20832.4 21342.9 21579.6 21781.1 5411.6 5574.4 5708.3 5830.9 5838.0 5178.3 5642.7 5706.7 5770.7 5771.8 5474.8 5652.3 5729.4 5735.5 5780.5 5609.7 5667.3 5694.2 5705.9 5707.9 4376.5 4676.8 5062.0 5062.1 5069.6 24894.8 25474.6 25859.7 26167.1 26310.9 2672.8 2829.0 2839.0 2902.7 3078.3 16503.3 16980.2 17093.7 17165.7 17834.6 1914.2 1927.6 1937.8 1981.4 1990.3 113740.0 148218.0 150848.0 152481.0 153745.0 9681.1 10168.3 10367.1 10367.4 10658.0 12832.9 12862.2 12902.4 12918.9 12936.4 6029.4 6181.8 7322.4 7516.6 7603.9 7254.9 7286.9 7289.2 7440.1 7444.9 3624.5 4011.2 4110.7 4125.8 4220.2 2008.1 2040.4 2053.5 2055.0 2066.5 983.9 992.6 995.8 1010.9 1031.8 19004.4 21221.8 21432.8 21724.8 21736.7 916.8 994.6 1030.9 1034.6 1039.6 11138.8 11370.3 11392.6 11393.6 11437.8 664164.0 675431.0 681357.0 681499.0 688222.0 96608.0 150148.0 151899.0 153330.2 163487.2 947.4 1005.1 1024.7 1025.9 1059.8 18865.1 19537.9 19572.0 19589.0 19644.8 54074.5 57797.0 58337.4 58430.0 59411.2 86014.8 87084.0 87332.1 87547.6 88457.0 8032.4 8386.7 8425.5 8529.0 8599.9 12075.8 12270.6 12602.6 13319.4 13361.5 9500.0 11072.1 11495.8 11526.8 11535.1 10938.3 10963.0 11131.3 11185.4 11295.3 9301.7 10905.1 11205.0 11238.3 11625.0 7146.4 7422.5 7481.9 7517.5 8657.9 8017.6 8267.8 10218.5 11017.8 11081.3 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5414.70 Median:5139.58 stddev:2993.59 (58.2%) stddev-sans-first:2986.06 04:17:20 INFO - PROCESS | 1645 | Values: 1305.8 1418.3 1426.3 1443.7 1449.6 8036.4 8440.3 8449.0 8481.0 8485.2 8083.8 8350.8 8457.1 8537.1 8542.1 2256.6 2293.8 2294.8 2301.7 2304.1 1985.7 2260.9 2308.9 2317.7 2327.9 1984.1 2046.9 2208.5 2280.4 2282.1 2204.3 2225.4 2234.4 2237.0 2240.5 2206.9 2257.8 2267.7 2268.3 2270.1 8390.3 8482.3 8487.7 8494.6 8495.2 5037.5 5159.8 5187.6 5195.4 5200.0 2963.9 3197.2 3232.7 3484.8 3493.5 1152.9 1159.8 1178.3 1195.0 1220.6 10773.5 10795.5 10841.5 10855.5 10870.0 4192.1 4426.0 4476.6 4503.2 4510.8 8758.3 8784.2 8786.9 8792.6 8794.7 5088.2 5096.4 5099.6 5107.5 5108.8 4600.1 4623.2 4701.5 4704.4 4763.4 3549.3 3597.9 3618.6 3646.1 3674.9 1108.3 1108.5 1113.6 1124.6 1124.7 689.3 755.8 773.4 783.3 822.8 4872.6 5401.3 5611.5 5669.8 5751.2 831.0 833.1 970.0 972.8 1004.4 3708.0 3760.2 3790.3 3830.5 3870.3 9812.5 9954.7 9975.8 10199.7 10201.4 9919.6 9920.6 9923.5 10110.8 10127.9 7553.1 7791.2 7932.0 7951.6 7952.0 5180.2 5461.6 5749.1 5797.1 5839.7 8979.4 9030.9 9299.0 9389.8 9414.5 9249.6 9252.3 9509.0 9570.9 9597.7 7095.0 7332.6 7340.1 7349.6 7357.0 7813.6 7851.0 7962.6 7968.8 7970.6 7162.5 7179.9 7194.0 7256.3 7258.6 6828.5 7009.0 7029.5 7125.3 7130.4 6289.5 6322.3 6388.6 6450.4 6465.7 4828.9 4895.8 5002.3 5023.9 5182.6 4989.1 5020.9 5086.6 5113.5 5119.4 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4339.01 Median:4817.32 stddev:1334.99 (27.7%) stddev-sans-first:1304.00 04:17:20 INFO - PROCESS | 1645 | Values: 307.5 351.0 353.7 365.8 366.7 3935.8 4880.1 5018.1 5322.6 5372.0 4109.7 4626.7 4767.3 5023.5 5224.6 3364.3 3997.7 4005.5 4019.0 4025.3 4052.6 4053.5 4058.8 4059.8 4069.1 3214.6 3591.7 3957.4 4028.6 4038.6 3435.7 3964.5 3965.9 3983.7 3985.8 2826.8 3196.8 3444.2 3450.0 3451.7 4628.0 5105.7 5110.5 5121.6 5144.6 4179.8 4361.6 4505.9 4558.2 4571.3 4634.8 4976.1 5315.3 5541.3 5543.7 3224.6 3542.4 3725.1 3799.7 3821.2 4937.1 5541.8 5591.7 5728.7 5797.5 2969.8 3799.2 3804.7 3816.6 3848.5 3883.6 3900.4 3901.5 3902.3 3910.5 4246.3 4548.9 4556.5 4570.3 4579.5 4439.3 5567.7 5571.5 5582.9 5623.7 1181.8 1206.7 1239.2 1263.3 1269.4 741.0 793.8 803.9 852.3 870.4 4887.0 5027.7 5028.5 5028.5 5073.1 4720.9 4931.1 5127.5 5491.9 5506.9 4198.6 4585.4 4810.9 5048.2 5055.7 2349.7 2457.8 2592.5 2876.9 2912.7 5090.9 5261.0 5555.5 5758.5 5798.9 4738.6 4766.9 4860.2 5468.0 5509.9 5128.8 5539.8 5615.5 5642.3 5776.6 4751.8 5293.8 5411.6 5511.0 5533.4 4568.4 4580.8 4934.5 4942.0 5114.4 4823.7 5175.8 5579.6 5742.3 5757.2 3116.0 3146.9 3630.5 3657.0 3664.3 4370.6 5068.5 5231.4 5523.9 5673.6 4519.7 4560.2 5546.3 5586.6 5723.5 5004.3 5060.0 5292.3 5395.6 5611.2 4737.6 4810.1 5128.4 5439.8 5520.1 4984.5 5187.1 5281.0 5494.9 5623.9 5237.6 5250.5 5458.4 5500.3 5521.6 04:17:20 INFO - PROCESS | 1645 | 04:17:20 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19503.77 Median:4138.58 stddev:27226.88 (657.9%) stddev-sans-first:27266.67 04:17:20 INFO - PROCESS | 1645 | Values: 705.6 705.6 720.1 751.2 853.5 14254.3 14538.1 14949.9 15019.9 15216.2 13497.2 13560.5 13961.9 13965.9 13979.3 828.4 851.1 853.0 853.8 855.8 829.2 850.3 853.3 863.4 868.9 882.2 904.2 905.3 907.3 907.3 898.7 903.4 904.0 904.8 905.1 675.1 676.9 677.0 677.9 678.7 14760.3 15059.7 15098.9 15137.5 15151.8 13632.9 13641.0 13923.7 13939.6 14048.2 11543.0 11563.0 11618.8 11994.7 12047.8 3562.1 3633.8 3653.0 3693.5 3794.8 102928.5 104320.0 104545.4 104600.0 105656.4 9333.5 10585.1 10720.0 10757.8 10841.6 20363.3 21882.9 22129.1 22205.2 22209.8 7302.9 7340.2 7418.5 7484.3 7594.8 3372.8 4128.1 4149.0 4153.3 4160.1 3111.2 3559.2 3623.0 3623.2 3670.0 661.7 664.7 670.1 680.8 681.6 649.7 653.4 670.8 679.4 721.4 9619.9 9877.7 9957.8 10039.5 10380.6 560.8 567.9 590.9 650.8 661.2 4373.6 4834.4 4909.0 4996.0 5003.2 70065.0 76771.0 91675.0 96291.5 97697.5 59124.0 61433.0 71171.0 85202.6 91144.0 79280.6 80028.6 80301.6 80404.0 84487.0 10683.6 10764.4 10918.4 10993.8 10996.5 47177.5 47332.5 47396.3 48326.0 49917.0 63542.4 64231.7 64734.7 65189.7 65734.5 13606.9 13917.6 14112.9 14158.9 14293.9 14210.0 14277.0 14357.1 14783.9 15031.8 13518.5 13609.1 13778.0 13782.7 14510.2 13455.2 13525.8 13828.4 13983.9 14263.8 13077.7 14071.7 14076.9 14086.4 14088.5 10730.0 10850.0 11202.9 11257.7 11700.6 8398.6 9793.5 10382.4 10921.3 11401.1 04:17:20 INFO - PROCESS | 1645 | -------- Summary: end -------- 04:17:20 INFO - PROCESS | 1645 | 04:17:21 INFO - TEST-INFO | 1645: exit 0 04:17:21 INFO - TEST-OK | dromaeo_css | took 1118563ms 04:17:21 INFO - TEST-START | kraken 04:17:21 INFO - operating with platform_type : mac_ 04:17:21 INFO - Initialising browser for kraken test... 04:17:21 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpUbuQWH/profile http://localhost:49240/getInfo.html) 04:17:23 INFO - PROCESS | 1655 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:17:23 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 04:17:23 INFO - PROCESS | 1655 | colorDepth:24 04:17:23 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 04:17:23 INFO - PROCESS | 1655 | __metrics 04:17:23 INFO - PROCESS | 1655 | 1486556243948 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:17:24 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:17:24 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:17:24 INFO - TEST-INFO | 1655: exit 0 04:17:24 INFO - Browser initialized. 04:17:24 INFO - Running cycle 1/1 for kraken test... 04:17:24 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpUbuQWH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:36 INFO - PROCESS | 1656 | RSS: Main: 236318720 04:17:36 INFO - PROCESS | 1656 | 04:17:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:40 INFO - PROCESS | 1656 | RSS: Main: 288620544 04:17:40 INFO - PROCESS | 1656 | 04:17:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 04:17:43 INFO - PROCESS | 1656 | RSS: Main: 290512896 04:17:43 INFO - PROCESS | 1656 | 04:17:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 04:17:47 INFO - PROCESS | 1656 | RSS: Main: 381079552 04:17:47 INFO - PROCESS | 1656 | 04:17:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 04:17:50 INFO - PROCESS | 1656 | RSS: Main: 285310976 04:17:50 INFO - PROCESS | 1656 | 04:17:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:17:53 INFO - PROCESS | 1656 | RSS: Main: 286699520 04:17:53 INFO - PROCESS | 1656 | 04:17:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 04:17:56 INFO - PROCESS | 1656 | RSS: Main: 328589312 04:17:56 INFO - PROCESS | 1656 | 04:17:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 04:18:00 INFO - PROCESS | 1656 | RSS: Main: 330440704 04:18:00 INFO - PROCESS | 1656 | 04:18:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 04:18:03 INFO - PROCESS | 1656 | RSS: Main: 329535488 04:18:03 INFO - PROCESS | 1656 | 04:18:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:18:06 INFO - PROCESS | 1656 | RSS: Main: 306343936 04:18:06 INFO - PROCESS | 1656 | 04:18:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:18:08 INFO - PROCESS | 1656 | RSS: Main: 309682176 04:18:08 INFO - PROCESS | 1656 | 04:18:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:18:11 INFO - PROCESS | 1656 | RSS: Main: 311611392 04:18:11 INFO - PROCESS | 1656 | 04:18:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:18:15 INFO - PROCESS | 1656 | RSS: Main: 329895936 04:18:15 INFO - PROCESS | 1656 | 04:18:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:19 INFO - PROCESS | 1656 | RSS: Main: 324534272 04:18:19 INFO - PROCESS | 1656 | 04:18:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 04:18:21 INFO - PROCESS | 1656 | RSS: Main: 368189440 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | __start_tp_report 04:18:21 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:18:21 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:18:21 INFO - PROCESS | 1656 | |i|pagename|runs| 04:18:21 INFO - PROCESS | 1656 | |0;ai-astar;115;115;115;115;116;117;98;144;170;161 04:18:21 INFO - PROCESS | 1656 | |1;audio-beat-detection;140;116;112;150;117;112;114;107;133;122 04:18:21 INFO - PROCESS | 1656 | |2;audio-dft;186;183;185;184;185;184;184;182;184;182 04:18:21 INFO - PROCESS | 1656 | |3;audio-fft;81;60;59;91;62;61;60;60;78;58 04:18:21 INFO - PROCESS | 1656 | |4;audio-oscillator;91;71;89;70;70;71;86;69;74;85 04:18:21 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;117;126;89;84;88;85;89;87;88;84 04:18:21 INFO - PROCESS | 1656 | |6;imaging-darkroom;112;150;95;95;94;99;95;95;94;95 04:18:21 INFO - PROCESS | 1656 | |7;imaging-desaturate;85;85;85;85;88;85;85;88;85;84 04:18:21 INFO - PROCESS | 1656 | |8;json-parse-financial;63;59;59;58;59;60;60;59;59;59 04:18:21 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;71;68;74;71;70;71;70;72;71 04:18:21 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;100;104;89;90;88;88;89;90;89;89 04:18:21 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;145;144;143;143;165;136;164;151;203;160 04:18:21 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;175;174;178;175;181;232;188;174;173;173 04:18:21 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;69;81;81;82;83 04:18:21 INFO - PROCESS | 1656 | __end_tp_report 04:18:21 INFO - PROCESS | 1656 | __start_cc_report 04:18:21 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2071 04:18:21 INFO - PROCESS | 1656 | __end_cc_report 04:18:21 INFO - PROCESS | 1656 | __startTimestamp1486556301165__endTimestamp 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:18:21 INFO - PROCESS | 1656 | Number of tests: 14 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:126.60 Median:130.50 stddev:23.41 (17.9%) stddev-sans-first:24.45 04:18:21 INFO - PROCESS | 1656 | Values: 115.0 115.0 115.0 115.0 116.0 117.0 98.0 144.0 170.0 161.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:119.50 stddev:14.06 (11.8%) stddev-sans-first:13.37 04:18:21 INFO - PROCESS | 1656 | Values: 140.0 116.0 112.0 150.0 117.0 112.0 114.0 107.0 133.0 122.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.29 (0.7%) stddev-sans-first:1.12 04:18:21 INFO - PROCESS | 1656 | Values: 186.0 183.0 185.0 184.0 185.0 184.0 184.0 182.0 184.0 182.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:67.00 Median:61.50 stddev:11.77 (19.1%) stddev-sans-first:11.34 04:18:21 INFO - PROCESS | 1656 | Values: 81.0 60.0 59.0 91.0 62.0 61.0 60.0 60.0 78.0 58.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:8.97 (11.3%) stddev-sans-first:8.10 04:18:21 INFO - PROCESS | 1656 | Values: 91.0 71.0 89.0 70.0 70.0 71.0 86.0 69.0 74.0 85.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:87.50 stddev:14.92 (17.1%) stddev-sans-first:13.23 04:18:21 INFO - PROCESS | 1656 | Values: 117.0 126.0 89.0 84.0 88.0 85.0 89.0 87.0 88.0 84.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:95.00 stddev:17.59 (18.5%) stddev-sans-first:18.31 04:18:21 INFO - PROCESS | 1656 | Values: 112.0 150.0 95.0 95.0 94.0 99.0 95.0 95.0 94.0 95.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 04:18:21 INFO - PROCESS | 1656 | Values: 85.0 85.0 85.0 85.0 88.0 85.0 85.0 88.0 85.0 84.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.00 stddev:1.35 (2.3%) stddev-sans-first:0.60 04:18:21 INFO - PROCESS | 1656 | Values: 63.0 59.0 59.0 58.0 59.0 60.0 60.0 59.0 59.0 59.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:2.41 (3.4%) stddev-sans-first:1.62 04:18:21 INFO - PROCESS | 1656 | Values: 65.0 71.0 68.0 74.0 71.0 70.0 71.0 70.0 72.0 71.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:89.00 stddev:5.60 (6.3%) stddev-sans-first:5.05 04:18:21 INFO - PROCESS | 1656 | Values: 100.0 104.0 89.0 90.0 88.0 88.0 89.0 90.0 89.0 89.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:155.50 stddev:19.42 (12.5%) stddev-sans-first:20.23 04:18:21 INFO - PROCESS | 1656 | Values: 145.0 144.0 143.0 143.0 165.0 136.0 164.0 151.0 203.0 160.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.30 Median:176.50 stddev:18.07 (10.2%) stddev-sans-first:18.98 04:18:21 INFO - PROCESS | 1656 | Values: 175.0 174.0 178.0 175.0 181.0 232.0 188.0 174.0 173.0 173.0 04:18:21 INFO - PROCESS | 1656 | 04:18:21 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:75.00 stddev:7.50 (10.0%) stddev-sans-first:7.63 04:18:21 INFO - PROCESS | 1656 | Values: 67.0 68.0 67.0 66.0 67.0 69.0 81.0 81.0 82.0 83.0 04:18:21 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:18:21 INFO - PROCESS | 1656 | 04:18:22 INFO - TEST-INFO | 1656: exit 0 04:18:22 INFO - TEST-OK | kraken | took 60644ms 04:18:22 INFO - SUITE-END | took 1179s 04:18:22 INFO - Completed test suite (00:19:39) 04:18:22 INFO - javascript benchmark 04:18:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7097.514199616937, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3261.2499125201202, 3268.8692452301907, 3272.9018129456117, 3281.917952051197, 3315.5026745988116, 5364.061289274371, 5463.726813659321, 5486.615936884415, 5537.612367134302, 5538.889222215558, 5443.8094666686675, 5486.506214440699, 5531.9723401383035, 5550.111982082867, 5565.276514053177, 3992.0419099416076, 4740.976295118519, 4985.20236762119, 5036.244563315514, 5117.641765076446, 5155.3040339554245, 5187.584993200554, 5202.06362854686, 5209.479052094779, 5218.217267409881, 4966.602671786249, 4997.375328083989, 5002.049610573979, 5162.328897243335, 5172.40517340505, 5000.249962505617, 5014.423341315723, 5016.799328026875, 5061.721605311706, 5124.897502049938, 4984.501549845023, 5006.5744411725045, 5017.573506251973, 5030.0694371541385, 5074.695518268915, 5494.917576236359, 5504.779808807642, 5509.035918714209, 5520.779168833242, 5525.696086715229, 5787.450192231731, 5849.912251316234, 5850.414958504116, 5886.175935369051, 5941.702914854283, 6094.9390506094815, 6112.390397230779, 6116.579253789288, 6128.533679014817, 6136.570440069197, 5564.8330550083965, 5565.109582466826, 5575.60970732053, 5579.2746942897165, 5585.748641311069, 6045.727942242522, 6113.57204995646, 6125.601835880653, 6209.161763162006, 6240.032794916795, 5753.0, 5793.102069179284, 5805.071188609802, 5809.2738407699035, 5812.59311848175, 5920.911186332209, 5940.821775346746, 5948.048312270016, 6004.189434426384, 6095.664738439428, 6020.307664618537, 6055.636661800306, 6057.970145075347, 6076.605020673642, 6080.118382834465, 6094.634321940698, 6098.664573448503, 6103.23709536308, 6107.45032947028, 6114.6636934969, 4717.386739723814, 4877.487863774323, 4882.804687812519, 4888.0, 4890.0, 4268.423762791983, 4320.071184695243, 4351.282038463618, 4376.824927002885, 4380.67144964129, 5591.3569939456665, 5591.888162236733, 5595.720213989365, 5596.496315331639, 5596.748146333425, 4810.5670489656095, 5961.135635332853, 5966.731497082642, 5970.731317090741, 5979.0, 5575.080111781592, 5586.720663966867, 5588.413216612278, 5593.328800543879, 5600.103983362643, 4291.506476755213, 5204.245384419238, 5243.9213411798855, 5249.2126181073145, 5251.632385732962, 5653.784436346123, 5732.541396688255, 6174.753009879554, 6213.161223234805, 6246.187995560548, 6151.384861513813, 6154.292256390572, 6157.9384206157365, 6158.938410615837, 6159.5688301818445, 6327.3988971047675, 6328.46208072319, 6340.936590634219, 6347.428731414195, 6357.80926572185, 5787.508061814625, 5826.067829147316, 5831.300243970751, 5923.0, 5955.374685657944, 6102.79223100439, 6151.446369826737, 6153.538484613582, 6161.537884658579, 6165.106059621419, 5911.556633252438, 5925.674087925206, 5928.940710593011, 5937.435943585352, 5952.5237980960555, 5609.5512359012, 5664.688442135557, 5676.318841739017, 5898.587098903207, 5902.675352855463, 5261.73691315425, 5705.372409035086, 5775.104858746901, 5798.797042103675, 5818.912716309174, 5715.771369145354, 5856.092305692625, 5892.381299963442, 5898.380670029585, 5915.467607915309, 5556.138798486241, 5719.799807006735, 5764.337101233412, 5770.682612456355, 5786.623869448473], "value": 5509.04989371815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.67246909755875, 450.88727818045487, 499.4965154883801, 513.0252520211595, 514.7014731455755, 26088.08691695791, 37090.814545927264, 39018.60981390185, 39080.413793793115, 40814.12265834741, 39608.0, 40147.19705605893, 40229.0, 40290.79854600723, 40308.0, 7711.845763084735, 9056.275497960147, 9236.538173091352, 9263.722088337361, 9286.257099432029, 7787.805304867367, 10737.624183153592, 10971.561137554489, 10983.560657573687, 10987.450627468634, 10940.671779846578, 10990.0, 11011.724706882313, 11021.559137634486, 11035.944820275887, 7859.6463159157975, 8036.156203598655, 10930.890691093107, 10983.286086404438, 11017.83473247906, 10526.0, 10808.351498910091, 10854.077403420719, 10868.184886133571, 10927.672169834917, 36913.63086369129, 37595.62404375948, 37636.247275054346, 38018.80990595029, 38091.23817523662, 3774.3394905891632, 3805.899143672686, 3835.5972622874474, 3841.3661745811905, 3966.3257246268204, 28141.57787633187, 28660.713392866222, 28727.856360718273, 29314.853425732734, 29547.556786648, 2938.353562216309, 2938.8971386001385, 2952.35048289376, 2958.6301712285963, 2962.4963756161505, 438436.0, 442378.78810606065, 443226.0, 443648.0, 446617.99999999674, 46758.76620616875, 47926.28110578344, 56050.0, 56501.71749141228, 56552.43447565554, 57630.4236957625, 58624.0, 59036.11445828316, 61192.38807611956, 61272.387276127556, 13177.341132943315, 13303.667408314675, 13317.467301307839, 13328.00039997004, 13338.93330533341, 10360.637377691744, 10559.472026398804, 10679.83980240297, 10703.78592428147, 10708.571657133627, 14957.775633365509, 14970.775438368215, 14973.850261497464, 14976.925115374353, 15131.243437828065, 6796.320367963164, 6934.0, 6963.9651801740665, 6985.615791131435, 7001.579905205704, 712.4763298975249, 819.4755356571799, 826.6566828903033, 838.6980686952692, 859.9570021498901, 28498.002569909953, 28829.56755648667, 28871.278218044295, 29229.123126306244, 29382.11853644394, 984.9015098490094, 1006.2603986070379, 1036.7926414717087, 1045.8692663417073, 1106.778644271149, 14339.0, 14754.852451475348, 15041.473548425753, 15118.848811511964, 15158.86308526865, 585462.0, 590164.0, 590243.0, 591299.0, 591646.0, 43706.562934370886, 46896.06207875823, 57108.0, 57243.42756572464, 57262.0, 1310.502009236503, 1345.663584103974, 1351.8715722006393, 1353.769859123971, 1354.3905242640653, 27102.864485677444, 28406.43187136287, 28558.000469984283, 28609.570856436752, 28815.0, 46832.0, 47139.05721885612, 47316.7634161827, 47320.29019564778, 47447.81380465516, 503283.0, 507082.0, 507805.0, 509141.0, 509443.0, 36322.8183859079, 36693.266134676625, 36698.8165059173, 36818.447723283636, 36876.63123368839, 41640.0, 42014.159716806105, 42098.73703788891, 42452.57547424486, 43333.56666433295, 33289.0, 35345.29309413849, 35482.46776298306, 36378.90863274106, 36600.81699591485, 34968.82515587406, 35087.29825403529, 35480.29039419249, 36383.81808090943, 36551.63448365482, 34492.0, 35366.29267414689, 35777.46333804943, 35800.92597222087, 35978.0, 29769.958051468093, 30424.543631845096, 31240.843795780875, 31254.843725781226, 31517.527237091002, 23293.88353058224, 23844.403889902893, 23941.119288988, 23972.88013559991, 24003.879980599984], "value": 18955.037827628257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.53528363216947, 359.6637144270109, 374.3804004372763, 388.74148691120405, 401.7569370530829, 14114.364853581588, 14216.004879658429, 15073.397064117422, 19682.90158549205, 20225.190992360287, 20166.596668066668, 20832.375028749124, 21342.932853357346, 21579.568408631858, 21781.128754849786, 5411.6200368906075, 5574.386817450067, 5708.315002199742, 5830.854228644275, 5838.0, 5178.275041494194, 5642.7178641067985, 5706.714664266791, 5770.682612456313, 5771.826845194651, 5474.753636086386, 5652.265205523277, 5729.424408287712, 5735.455131762496, 5780.53755699543, 5609.747561359728, 5667.3482549506925, 5694.202811606378, 5705.942940570582, 5707.857303567403, 4376.474823021258, 4676.789544470507, 5062.012907483042, 5062.0888240116765, 5069.645124841262, 24894.751052489424, 25474.617880731803, 25859.74140258592, 26167.084152054776, 26310.947562097685, 2672.812903096784, 2828.9717102828913, 2838.9716102838916, 2902.7097290270804, 3078.3227689908185, 16503.257353419125, 16980.21707720684, 17093.743593846102, 17165.656686866194, 17834.55413614657, 1914.2151717795637, 1927.6170321086404, 1937.7965313642005, 1981.3560592807396, 1990.2735501541977, 113740.0, 148218.0, 150848.0, 152481.0, 153745.0, 9681.080297371738, 10168.33905796121, 10367.118794902517, 10367.377957322586, 10658.040776330012, 12832.935835320764, 12862.228266304051, 12902.419391127421, 12918.870811291954, 12936.353182340845, 6029.427204415661, 6181.783637572664, 7322.414206863545, 7516.586587737727, 7603.885941710879, 7254.89710507421, 7286.931676274005, 7289.230092564791, 7440.14438339587, 7444.8883266751045, 3624.4563315502887, 4011.1777085697945, 4110.725675040687, 4125.793710314472, 4220.240356735756, 2008.116428771336, 2040.36748607932, 2053.4968932611605, 2054.989725051395, 2066.524699319165, 983.896690847465, 992.5781542844262, 995.8141878576599, 1010.8938561450942, 1031.7523794289316, 19004.42986710401, 21221.78778212198, 21432.78567214339, 21724.782752172592, 21736.673949890763, 916.757059379265, 994.5872462927804, 1030.9175265978704, 1034.6430481484026, 1039.6205385034407, 11138.777224455627, 11370.31778093317, 11392.601258956063, 11393.601223957121, 11437.771244575062, 664164.0, 675431.0, 681357.0, 681499.0, 688222.0, 96608.0, 150148.0, 151899.0, 153330.23334883476, 163487.18256408643, 947.3605316411532, 1005.0602686488157, 1024.6977141743055, 1025.9487025648689, 1059.7986382587283, 18865.05674716231, 19537.92541410236, 19572.0, 19589.0, 19644.803551964298, 54074.45925540852, 57797.0, 58337.4166258332, 58430.0, 59411.21766347017, 86014.84962875978, 87084.0, 87332.1266787324, 87547.56226218828, 88457.0, 8032.437729358889, 8386.748397548083, 8425.4944703318, 8529.0, 8599.871001934851, 12075.75848483043, 12270.631881043582, 12602.621921342374, 13319.400626971616, 13361.465541378237, 9499.952500237454, 11072.058874995393, 11495.8275625864, 11526.769464610494, 11535.077193824307, 10938.343699378063, 10963.0, 11131.332120072822, 11185.440727963407, 11295.322280662858, 9301.674441394518, 10905.127589792955, 11205.0, 11238.269512481662, 11625.0, 7146.3568278855155, 7422.517536360119, 7481.850362992818, 7517.548947063194, 8657.870131948152, 8017.559034253172, 8267.793305167419, 10218.540165692413, 11017.83473247886, 11081.335119892832], "value": 9681.640374178784, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.811711342678, 1418.2908545727137, 1426.2726009735031, 1443.7184748973955, 1449.6158517992733, 8036.437449378546, 8440.324774018063, 8448.957755211222, 8480.999999999993, 8485.193906578881, 8083.757487275377, 8350.791230219247, 8457.069722330538, 8537.10360412157, 8542.145785421455, 2256.638937769957, 2293.8050265727434, 2294.7934685878267, 2301.746807851135, 2304.06685292456, 1985.7021446783026, 2260.886955652211, 2308.9191878284264, 2317.6986991691056, 2327.8836058197107, 1984.0575726529928, 2046.9283575074876, 2208.503086805464, 2280.4184932842104, 2282.1327895399727, 2204.272590045281, 2225.432514708747, 2234.4078819112947, 2237.049254067015, 2240.4958884251078, 2206.933791986243, 2257.81937445004, 2267.682524446579, 2268.2514770125986, 2270.14869423966, 8390.32877369809, 8482.321414286842, 8487.66049358025, 8494.617742201615, 8495.150484951517, 5037.546620804109, 5159.7678104485385, 5187.636865419422, 5195.376554813409, 5199.974000130013, 2963.9110826675233, 3197.2486465680545, 3232.7413806895393, 3484.8431820568135, 3493.458513930345, 1152.9250598711058, 1159.7912375772441, 1178.3048001679051, 1195.0499353014288, 1220.6216073017304, 10773.4613269337, 10795.514201860935, 10841.51213195416, 10855.45722713869, 10870.0, 4192.119654872511, 4426.048399594103, 4476.597106260452, 4503.211937910853, 4510.797014134306, 8758.255548278341, 8784.209421152127, 8786.86819697705, 8792.648294068165, 8794.692185773596, 5088.211327244209, 5096.413912400048, 5099.64302498829, 5107.489251074863, 5108.770105345268, 4600.102979918884, 4623.214053610895, 4701.529847015339, 4704.435467743892, 4763.404574428197, 3549.254656522159, 3597.892063238107, 3618.588750387351, 3646.1431563582537, 3674.9081272967856, 1108.2685427617735, 1108.5067145120506, 1113.6269349767917, 1124.6401151631562, 1124.746931940307, 689.300360134464, 755.8223817402906, 773.3851587987564, 783.3145997252404, 822.7901885019281, 4872.634552408584, 5401.297831281908, 5611.523020543218, 5669.801556945569, 5751.22358481608, 830.9584520774058, 833.1388680631727, 969.9515024248759, 972.7616733900239, 1004.3672486333563, 3708.0, 3760.1539653577724, 3790.298794722985, 3830.502034735522, 3870.3033453978555, 9812.460314682618, 9954.701358959242, 9975.750606234902, 10199.694009179737, 10201.38791672502, 9919.553620086968, 9920.553575089138, 9923.503824808731, 10110.797784044278, 10127.949360253151, 7553.093628764584, 7791.181925897812, 7932.0, 7951.602419878983, 7952.0, 5180.248863914787, 5461.617686761889, 5749.108888122348, 5797.101449275369, 5839.708014599338, 8979.371443998858, 9030.909690903007, 9298.95350523243, 9389.81220375575, 9414.529273536433, 9249.583768730557, 9252.259819214582, 9508.952455237679, 9570.904290957002, 9597.712068637951, 7095.006699062239, 7332.5967071811565, 7340.11918569775, 7349.55902645843, 7357.043584334002, 7813.609319534115, 7851.0, 7962.641681124479, 7968.84062318762, 7970.641321140447, 7162.534435261691, 7179.856402871809, 7194.0, 7256.346928776436, 7258.564486130849, 6828.5220034597105, 7009.0, 7029.543079700008, 7125.3230943060325, 7130.393916517154, 6289.496840252677, 6322.304546499973, 6388.55280130405, 6450.41946224842, 6465.676716164079, 4828.8792780180775, 4895.755212239445, 5002.324686167466, 5023.899522009612, 5182.611304152279, 4989.126902791924, 5020.899582008413, 5086.593072554261, 5113.53978141969, 5119.385673719177], "value": 4353.967179957814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.5432982021698, 351.02601980371793, 353.70112255144403, 365.77139287945033, 366.7322854316351, 3935.783531905744, 4880.072786170634, 5018.146915024446, 5322.574194064475, 5371.973140134294, 4109.671226301896, 4626.676132670715, 4767.308740232665, 5023.547880690737, 5224.6081543884175, 3364.2934983653463, 3997.72015958883, 4005.539362973266, 4018.97990510047, 4025.335819589779, 4052.5542190359038, 4053.5338436079933, 4058.776767277813, 4059.776712280823, 4069.125138095309, 3214.5981752280964, 3591.6767490925813, 3957.406389041638, 4028.5971402859773, 4038.5759495253014, 3435.6736110069505, 3964.5242570891387, 3965.900852478711, 3983.7410568313257, 3985.7807820569856, 2826.812738649772, 3196.824174670392, 3444.2422667013466, 3449.9827500862584, 3451.741119416054, 4627.953720462786, 5105.668131571436, 5110.514501122388, 5121.615878809104, 5144.57885506138, 4179.770112643803, 4361.629261512775, 4505.864824055284, 4558.202314594932, 4571.268597024494, 4634.814607415733, 4976.138720235879, 5315.255864179018, 5541.307336582927, 5543.667379957175, 3224.6452890182063, 3542.415501442259, 3725.1245957200035, 3799.6891072579915, 3821.159344944108, 4937.136001199811, 5541.806036788693, 5591.664500130005, 5728.713564321767, 5797.53589735901, 2969.792114551961, 3799.2211596622624, 3804.7336686432236, 3816.5992570779667, 3848.461215429842, 3883.6310550497656, 3900.356441187229, 3901.5318161820765, 3902.2585708715255, 3910.4720862683744, 4246.278132717383, 4548.931766023512, 4556.453225612948, 4570.314452832035, 4579.473360563511, 4439.311906654474, 5567.693776842312, 5571.498565129158, 5582.860428489239, 5623.718814059281, 1181.8358916467444, 1206.7345184059493, 1239.1945235596934, 1263.286243272548, 1269.3653173413293, 740.9851802964018, 793.8213901872033, 803.8593246181895, 852.2542775071812, 870.4298684361753, 4886.992666013905, 5027.723475208899, 5028.497150285016, 5028.522290382408, 5073.097588404348, 4720.905581888343, 4931.13705101613, 5127.512886275797, 5491.919974024092, 5506.871091426248, 4198.6011328923705, 4585.426821647294, 4810.927836082462, 5048.16705243638, 5055.747212639427, 2349.7297810751984, 2457.832529548486, 2592.533343998061, 2876.878017573148, 2912.66504351998, 5090.872728181825, 5261.0, 5555.527780138653, 5758.510526605285, 5798.8550286242335, 4738.620910327166, 4766.880827979328, 4860.181094964912, 5468.0, 5509.889802204014, 5128.79484820603, 5539.750711218005, 5615.494605485526, 5642.319898048394, 5776.555861034742, 4751.7882939850115, 5293.841184764463, 5411.567074634098, 5511.0, 5533.446655334435, 4568.383268258743, 4580.793864276182, 4934.5312195341385, 4942.011597680406, 5114.437411884615, 4823.710577365369, 5175.844724658266, 5579.581531385081, 5742.339630942495, 5757.165211044459, 3115.9688403116584, 3146.8985854950583, 3630.5130985275173, 3656.9817150914073, 3664.303782281358, 4370.557715953792, 5068.467810879804, 5231.398389185292, 5523.944760552343, 5673.631213971079, 4519.683622146418, 4560.19872150492, 5546.306711661043, 5586.620094842724, 5723.542116630742, 5004.324416203766, 5059.988002399461, 5292.338457692788, 5395.550494001009, 5611.214429979887, 4737.573618374363, 4810.086083644096, 5128.358955130609, 5439.755211015435, 5520.1443776214755, 4984.501549844987, 5187.054854429589, 5280.970210808855, 5494.862628434321, 5623.859403514781, 5237.607179461479, 5250.501202385767, 5458.372287187024, 5500.28496295479, 5521.641093328921], "value": 3925.891408181922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.5519744961948, 705.6013352455863, 720.1430297945444, 751.1540910538042, 853.5348235211811, 14254.287285635715, 14538.055026423275, 14949.850501494995, 15019.924900375496, 15216.163111028889, 13497.190168589892, 13560.525381611646, 13961.860381396184, 13965.930170349135, 13979.301034948263, 828.3994104274383, 851.0510780479782, 852.957352132394, 853.7524118005771, 855.7603870916154, 829.2163905109674, 850.3409857360564, 853.2619284319051, 863.434450434963, 868.9087645797196, 882.2368651116802, 904.1997831918767, 905.3436258712413, 907.2741806554762, 907.3149771922223, 898.7393655839799, 903.4488961733338, 903.9548022598909, 904.8461761500495, 905.0813424374232, 675.0650349266301, 676.9120014398146, 676.9763058292937, 677.915260592426, 678.6572780745691, 14760.261986900612, 15059.698806023818, 15098.924505377512, 15137.470188543348, 15151.848481515153, 13632.93183534086, 13641.0, 13923.651908702263, 13939.581812545639, 14048.227347495884, 11543.01884339832, 11563.0, 11618.767624647544, 11994.70013249667, 12047.759044819142, 3562.1272788167066, 3633.8364773585254, 3652.954345566491, 3693.519842420468, 3794.7912864792424, 102928.48535757349, 104319.95680043178, 104545.43181852279, 104600.0, 105656.41515377199, 9333.533323333942, 10585.100266477435, 10719.98160174782, 10757.784844303227, 10841.566337346576, 20363.287284944956, 21882.9058547072, 22129.114835406726, 22205.222817201644, 22209.777902221093, 7302.890456643155, 7340.192578816433, 7418.517796343221, 7484.251574842581, 7594.848103037908, 3372.802655057468, 4128.1330920506425, 4149.024979129902, 4153.252414565347, 4160.1471698301775, 3111.175538482304, 3559.1635965548076, 3623.0217841182734, 3623.239119784822, 3670.0, 661.7022339946947, 664.7374287156546, 670.061913321354, 680.8195828105556, 681.6012632609879, 649.7108786589922, 653.4151934019121, 670.8356452669127, 679.3987321220756, 721.3688022979893, 9619.90380096204, 9877.654282100091, 9957.800843983225, 10039.548220330104, 10380.584776609003, 560.7981126794351, 567.85068515668, 590.8759160576327, 650.8210242183437, 661.1933441201726, 4373.608601463517, 4834.371531700856, 4909.0, 4995.975020124876, 5003.224500202475, 70065.0, 76771.0, 91675.0, 96291.51854240824, 97697.51151244198, 59124.0, 61433.0, 71171.0, 85202.57398712967, 91144.0, 79280.60359698164, 80028.5998570015, 80301.59849200716, 80404.0, 84487.0, 10683.626073087406, 10764.40795756241, 10918.399488028235, 10993.780124397626, 10996.450177491253, 47177.528224717316, 47332.52667473281, 47396.2890556635, 48326.0, 49917.0, 63542.36457635365, 64231.67884160736, 64734.676326618064, 65189.67405162944, 65734.45452091198, 13606.86393136056, 13917.582472525435, 14112.929435352757, 14158.858411415755, 14293.928530357281, 14210.0, 14277.014007448208, 14357.066790658573, 14783.926080369529, 15031.849681503045, 13518.459261629425, 13609.11540749848, 13778.0, 13782.655433613838, 14510.201938893462, 13455.192688438725, 13525.837382636579, 13828.446862125402, 13983.860161398256, 14263.786043209153, 13077.738445231233, 14071.718565628425, 14076.929615351857, 14086.366113524711, 14088.506902258781, 10729.999057553558, 10850.0, 11202.887971120184, 11257.718557035812, 11700.648980530597, 8398.580070996548, 9793.510324483605, 10382.377057376581, 10921.290116142425, 11401.144914131308], "value": 7396.314007122511, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1491.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 116.0, 117.0, 98.0, 144.0, 170.0, 161.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 112.0, 150.0, 117.0, 112.0, 114.0, 107.0, 133.0, 122.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 185.0, 184.0, 185.0, 184.0, 184.0, 182.0, 184.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 91.0, 62.0, 61.0, 60.0, 60.0, 78.0, 58.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 89.0, 70.0, 70.0, 71.0, 86.0, 69.0, 74.0, 85.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 126.0, 89.0, 84.0, 88.0, 85.0, 89.0, 87.0, 88.0, 84.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 150.0, 95.0, 95.0, 94.0, 99.0, 95.0, 95.0, 94.0, 95.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 88.0, 85.0, 85.0, 88.0, 85.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 59.0, 58.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 68.0, 74.0, 71.0, 70.0, 71.0, 70.0, 72.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 104.0, 89.0, 90.0, 88.0, 88.0, 89.0, 90.0, 89.0, 89.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 143.0, 143.0, 165.0, 136.0, 164.0, 151.0, 203.0, 160.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 178.0, 175.0, 181.0, 232.0, 188.0, 174.0, 173.0, 173.0], "value": 182.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 69.0, 81.0, 81.0, 82.0, 83.0], "value": 73.1, "unit": "score"}]}]} 04:18:22 INFO - Return code: 0 04:18:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:18:22 ERROR - # TBPL SUCCESS # 04:18:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:22 INFO - 2017-02-08 04:18:22.451 screenresolution[1660:9789] starting screenresolution argv=screenresolution get 04:18:22 INFO - 2017-02-08 04:18:22.453 screenresolution[1660:9789] Display 0: 1600x1200x32@60 04:18:22 INFO - 2017-02-08 04:18:22.460 screenresolution[1661:9792] starting screenresolution argv=screenresolution list 04:18:22 INFO - Available Modes on Display 0 04:18:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:22 INFO - Intel Iris: 04:18:22 INFO - Chipset Model: Intel Iris 04:18:22 INFO - Type: GPU 04:18:22 INFO - Bus: Built-In 04:18:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:22 INFO - Vendor: Intel (0x8086) 04:18:22 INFO - Device ID: 0x0a2e 04:18:22 INFO - Revision ID: 0x0009 04:18:22 INFO - Displays: 04:18:22 INFO - Display: 04:18:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:22 INFO - Display Serial Number: 1600x1200 60 04:18:22 INFO - Main Display: Yes 04:18:22 INFO - Mirror: Off 04:18:22 INFO - Online: Yes 04:18:22 INFO - Rotation: Supported 04:18:22 INFO - Return code: 0 04:18:22 INFO - Running post-action listener: _resource_record_post_action 04:18:22 INFO - [mozharness: 2017-02-08 12:18:22.700156Z] Finished run-tests step (success) 04:18:22 INFO - Running post-run listener: _resource_record_post_run 04:18:22 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37397504; Write bytes: 555946496; Read time: 237; Write time: 1697 04:18:22 INFO - TinderboxPrint: CPU usage
53.7% 04:18:22 INFO - TinderboxPrint: I/O read bytes / time
37,397,504 / 237 04:18:22 INFO - TinderboxPrint: I/O write bytes / time
555,946,496 / 1,697 04:18:22 INFO - TinderboxPrint: CPU idle
2,225.6 (46.3%) 04:18:22 INFO - TinderboxPrint: CPU system
959.5 (19.9%) 04:18:22 INFO - TinderboxPrint: CPU user
1,624.6 (33.8%) 04:18:22 INFO - TinderboxPrint: Swap in / out
289,857,536 / 0 04:18:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248592896; Write bytes: 241993216; Read time: 17841; Write time: 507 04:18:22 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 33989632; Write bytes: 300436480; Read time: 214; Write time: 1149 04:18:23 INFO - Running post-run listener: _upload_blobber_files 04:18:23 INFO - Blob upload gear active. 04:18:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:23 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 04:18:23 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'] 04:18:23 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 04:18:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:18:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:18:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:24 INFO - (blobuploader) - INFO - Done attempting. 04:18:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:18:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:18:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:25 INFO - (blobuploader) - INFO - Done attempting. 04:18:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:18:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:18:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:26 INFO - (blobuploader) - INFO - Done attempting. 04:18:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:18:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:29 INFO - (blobuploader) - INFO - Done attempting. 04:18:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:29 INFO - Return code: 0 04:18:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ede52e8a94ec537b05449f66336b4beb2d1279bd22a77d1ef7c76dd29a7c13d7a2ab0085504ef3b47c040b3585bd13c5e600edb6c70a3d5f4538dd70f359b627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4bb1496b2b324932b85c8ad588058a9e88293a9e0ba767a2753d2275c2cb74d23dc4cfdde5483dcf0912d10a9b47c71f5e2a4671e5050deb47181a6ed110a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b9c1a1f958d152c426b9f4b69ee752f37b0b32ba746ffc410ce1968b9d0702df999d2dccf3f5807e3a843f1cef5003340025bb69f0c140adb1b3b45fba5fc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf87ba05a7f2ec17da77db9e32ddd535b54d3c73f119cfd93c76bd8165e2a0a9d3f0417b64f249a89936f79ddadf46b9435b79a889f23c34a626cb86e170a621"} 04:18:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:29 INFO - Contents: 04:18:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ede52e8a94ec537b05449f66336b4beb2d1279bd22a77d1ef7c76dd29a7c13d7a2ab0085504ef3b47c040b3585bd13c5e600edb6c70a3d5f4538dd70f359b627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4bb1496b2b324932b85c8ad588058a9e88293a9e0ba767a2753d2275c2cb74d23dc4cfdde5483dcf0912d10a9b47c71f5e2a4671e5050deb47181a6ed110a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b9c1a1f958d152c426b9f4b69ee752f37b0b32ba746ffc410ce1968b9d0702df999d2dccf3f5807e3a843f1cef5003340025bb69f0c140adb1b3b45fba5fc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf87ba05a7f2ec17da77db9e32ddd535b54d3c73f119cfd93c76bd8165e2a0a9d3f0417b64f249a89936f79ddadf46b9435b79a889f23c34a626cb86e170a621"} 04:18:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:29 INFO - Copying logs to upload dir... 04:18:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.091802 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-08 04:18:29.366181) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 04:18:29.369876) ========= 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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ede52e8a94ec537b05449f66336b4beb2d1279bd22a77d1ef7c76dd29a7c13d7a2ab0085504ef3b47c040b3585bd13c5e600edb6c70a3d5f4538dd70f359b627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4bb1496b2b324932b85c8ad588058a9e88293a9e0ba767a2753d2275c2cb74d23dc4cfdde5483dcf0912d10a9b47c71f5e2a4671e5050deb47181a6ed110a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b9c1a1f958d152c426b9f4b69ee752f37b0b32ba746ffc410ce1968b9d0702df999d2dccf3f5807e3a843f1cef5003340025bb69f0c140adb1b3b45fba5fc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf87ba05a7f2ec17da77db9e32ddd535b54d3c73f119cfd93c76bd8165e2a0a9d3f0417b64f249a89936f79ddadf46b9435b79a889f23c34a626cb86e170a621"} build_url:https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/c15ookbsTKGUMYQ3JIESHw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ede52e8a94ec537b05449f66336b4beb2d1279bd22a77d1ef7c76dd29a7c13d7a2ab0085504ef3b47c040b3585bd13c5e600edb6c70a3d5f4538dd70f359b627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4bb1496b2b324932b85c8ad588058a9e88293a9e0ba767a2753d2275c2cb74d23dc4cfdde5483dcf0912d10a9b47c71f5e2a4671e5050deb47181a6ed110a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09b9c1a1f958d152c426b9f4b69ee752f37b0b32ba746ffc410ce1968b9d0702df999d2dccf3f5807e3a843f1cef5003340025bb69f0c140adb1b3b45fba5fc3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf87ba05a7f2ec17da77db9e32ddd535b54d3c73f119cfd93c76bd8165e2a0a9d3f0417b64f249a89936f79ddadf46b9435b79a889f23c34a626cb86e170a621"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 04:18:29.404369) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 04:18:29.404701) ========= 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.nLoyJwxWsn/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.8EtFsbj6Ua/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005130 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 04:18:29.453293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 04:18:29.453603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 04:18:29.453934) ========= ========= Total master_lag: 0.18 =========