builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0362 starttime: 1483339128.96 results: success (0) buildid: 20170101194120 builduid: c4df46e8a49d4b5f80a369d6ba489694 revision: 1458ff2725619c3db4b1dcb4f057f26a60208873 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:48.963524) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:48.963945) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:48.964222) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:48.989824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:48.990139) ========= 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.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-01-01 22:38:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2017-01-01 22:38:49 (8.20 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.583809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:49.597334) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:49.597704) ========= 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.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.022034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:49.646119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-01 22:38:49.646551) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1458ff2725619c3db4b1dcb4f057f26a60208873 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1458ff2725619c3db4b1dcb4f057f26a60208873 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-01-01 22:38:49,725 truncating revision to first 12 chars 2017-01-01 22:38:49,725 Setting DEBUG logging. 2017-01-01 22:38:49,725 attempt 1/10 2017-01-01 22:38:49,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1458ff272561?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-01 22:38:51,209 unpacking tar archive at: mozilla-aurora-1458ff272561/testing/mozharness/ program finished with exit code 0 elapsedTime=2.532221 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-01 22:38:52.205628) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:52.205957) ========= script_repo_revision: 1458ff2725619c3db4b1dcb4f057f26a60208873 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:52.206372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:52.206653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 22:38:52.224616) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-01 22:38:52.224919) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:38:52 INFO - MultiFileLogger online at 20170101 22:38:52 in /builds/slave/test 22:38:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:38:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:38:52 INFO - {'append_to_log': False, 22:38:52 INFO - 'base_work_dir': '/builds/slave/test', 22:38:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:38:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:38:52 INFO - 'branch': 'Mozilla-Aurora', 22:38:52 INFO - 'buildbot_json_path': 'buildprops.json', 22:38:52 INFO - 'config_files': ('talos/mac_config.py',), 22:38:52 INFO - 'default_actions': ('clobber', 22:38:52 INFO - 'read-buildbot-config', 22:38:52 INFO - 'download-and-extract', 22:38:52 INFO - 'populate-webroot', 22:38:52 INFO - 'create-virtualenv', 22:38:52 INFO - 'install', 22:38:52 INFO - 'run-tests'), 22:38:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:38:52 INFO - 'download_minidump_stackwalk': True, 22:38:52 INFO - 'download_symbols': 'ondemand', 22:38:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:38:52 INFO - 'tooltool.py': '/tools/tooltool.py', 22:38:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:38:52 INFO - '/tools/misc-python/virtualenv.py')}, 22:38:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:38:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:38:52 INFO - 'installer_path': 'installer.exe', 22:38:52 INFO - 'log_level': 'info', 22:38:52 INFO - 'log_name': 'talos', 22:38:52 INFO - 'log_to_console': True, 22:38:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:38:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:38:52 INFO - 'opt_config_files': (), 22:38:52 INFO - 'pip_index': False, 22:38:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:38:52 INFO - 'cmd': ('bash', 22:38:52 INFO - '-c', 22:38:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:38:52 INFO - 'enabled': True, 22:38:52 INFO - 'halt_on_failure': False, 22:38:52 INFO - 'name': 'check_screen_resolution'},), 22:38:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:38:52 INFO - 'cmd': ('bash', 22:38:52 INFO - '-c', 22:38:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:38:52 INFO - 'enabled': True, 22:38:52 INFO - 'halt_on_failure': False, 22:38:52 INFO - 'name': 'check_screen_resolution'},), 22:38:52 INFO - 'run_cmd_checks_enabled': True, 22:38:52 INFO - 'sps_profile': False, 22:38:52 INFO - 'sps_profile_interval': 0, 22:38:52 INFO - 'suite': 'dromaeojs', 22:38:52 INFO - 'system_bits': '32', 22:38:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:38:52 INFO - 'title': 't-yosemite-r7-0362', 22:38:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:38:52 INFO - 'use_talos_json': True, 22:38:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:38:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:38:52 INFO - 'work_dir': 'build'} 22:38:52 INFO - [mozharness: 2017-01-02 06:38:52.419382Z] Running clobber step. 22:38:52 INFO - Running pre-action listener: _resource_record_pre_action 22:38:52 INFO - Running main action method: clobber 22:38:52 INFO - rmtree: /builds/slave/test/build 22:38:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:38:54 INFO - Running post-action listener: _resource_record_post_action 22:38:54 INFO - [mozharness: 2017-01-02 06:38:54.418768Z] Finished clobber step (success) 22:38:54 INFO - [mozharness: 2017-01-02 06:38:54.418948Z] Running read-buildbot-config step. 22:38:54 INFO - Running pre-action listener: _resource_record_pre_action 22:38:54 INFO - Running main action method: read_buildbot_config 22:38:54 INFO - Using buildbot properties: 22:38:54 INFO - { 22:38:54 INFO - "project": "", 22:38:54 INFO - "product": "firefox", 22:38:54 INFO - "script_repo_revision": "production", 22:38:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:38:54 INFO - "repository": "", 22:38:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:38:54 INFO - "buildid": "20170101194120", 22:38:54 INFO - "pgo_build": "False", 22:38:54 INFO - "basedir": "/builds/slave/test", 22:38:54 INFO - "buildnumber": 15, 22:38:54 INFO - "slavename": "t-yosemite-r7-0362", 22:38:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:38:54 INFO - "platform": "yosemite_r7", 22:38:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:38:54 INFO - "branch": "mozilla-aurora", 22:38:54 INFO - "repo_path": "releases/mozilla-aurora", 22:38:54 INFO - "revision": "1458ff2725619c3db4b1dcb4f057f26a60208873", 22:38:54 INFO - "stage_platform": "macosx64", 22:38:54 INFO - "builduid": "c4df46e8a49d4b5f80a369d6ba489694", 22:38:54 INFO - "slavebuilddir": "test" 22:38:54 INFO - } 22:38:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:38:54 INFO - Running post-action listener: _resource_record_post_action 22:38:54 INFO - [mozharness: 2017-01-02 06:38:54.423109Z] Finished read-buildbot-config step (success) 22:38:54 INFO - [mozharness: 2017-01-02 06:38:54.423249Z] Running download-and-extract step. 22:38:54 INFO - Running pre-action listener: _resource_record_pre_action 22:38:54 INFO - Running main action method: download_and_extract 22:38:54 INFO - mkdir: /builds/slave/test/build/tests 22:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:38:54 INFO - trying https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:38:54 INFO - Downloading https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:38:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 22:38:56 INFO - Downloaded 1199 bytes. 22:38:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:38:56 INFO - Using the following test package requirements: 22:38:56 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:38:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:38:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:38:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'jsshell-mac.zip'], 22:38:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:38:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:38:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:38:56 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:38:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:38:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:38:56 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:38:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:38:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:38:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:38:56 INFO - Fetch https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:38:56 INFO - Http code: 200 22:38:56 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 22:38:56 INFO - x-amz-cf-id: cH0xFNAGcPw-EfkfDh1eFRkbzVBOoJrbbZQbmEnp1qKyN0XluElV-g== 22:38:56 INFO - x-amz-version-id: wSz95bUaewWPkfFwFaUAPkOuN8sicIyv 22:38:56 INFO - Expected file size: 16268482 22:38:56 INFO - Obtained file size: 16268482 22:39:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:39:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:39:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:39:03 INFO - Fetch https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:39:04 INFO - Http code: 200 22:39:04 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 22:39:04 INFO - x-amz-cf-id: 8gffUbgf-WS13HyzsCbzzaAIZlfxasb28SA_bqyVE3HHKxNyRgqxng== 22:39:04 INFO - x-amz-version-id: wSz95bUaewWPkfFwFaUAPkOuN8sicIyv 22:39:04 INFO - Expected file size: 16268482 22:39:04 INFO - Obtained file size: 16268482 22:39:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:39:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:39:10 INFO - Fetch https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:39:11 INFO - Http code: 200 22:39:11 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 22:39:11 INFO - x-amz-cf-id: eCXHQ0jszTHgT5Z_Yi_QRk4__pwNvBlS7JkZqxCRsa7n6XAzEqL0XA== 22:39:11 INFO - x-amz-version-id: ER46mKTTFe91Z19tVo281DSAbTp3xAXU 22:39:11 INFO - Expected file size: 14409038 22:39:11 INFO - Obtained file size: 14409038 22:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:11 INFO - trying https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:39:11 INFO - Downloading https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:39:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 22:39:14 INFO - Downloaded 109823650 bytes. 22:39:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:39:14 INFO - mkdir: /builds/slave/test/properties 22:39:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:39:14 INFO - Writing to file /builds/slave/test/properties/build_url 22:39:14 INFO - Contents: 22:39:14 INFO - build_url:https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:39:15 INFO - Running post-action listener: _resource_record_post_action 22:39:15 INFO - Running post-action listener: set_extra_try_arguments 22:39:15 INFO - [mozharness: 2017-01-02 06:39:15.410553Z] Finished download-and-extract step (success) 22:39:15 INFO - [mozharness: 2017-01-02 06:39:15.410679Z] Running populate-webroot step. 22:39:15 INFO - Running pre-action listener: _resource_record_pre_action 22:39:15 INFO - Running main action method: populate_webroot 22:39:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:39:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:39:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:39:15 INFO - '--tppagecycles', 22:39:15 INFO - '7'], 22:39:15 INFO - 'tests': ['tsvgm']}}, 22:39:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:39:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:39:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:39:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:39:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['damp', 'tps']}, 22:39:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'tests': ['damp', 'tps']}, 22:39:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['dromaeo_dom']}, 22:39:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:39:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['basic_compositor_video']}, 22:39:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:39:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'other-e10s': {'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['a11yr', 22:39:15 INFO - 'ts_paint', 22:39:15 INFO - 'tpaint', 22:39:15 INFO - 'sessionrestore', 22:39:15 INFO - 'sessionrestore_no_auto_restore', 22:39:15 INFO - 'tabpaint']}, 22:39:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['tsvgx', 22:39:15 INFO - 'tsvgr_opacity', 22:39:15 INFO - 'tart', 22:39:15 INFO - 'tscrollx', 22:39:15 INFO - 'cart']}, 22:39:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:39:15 INFO - 'tsvgr_opacity', 22:39:15 INFO - 'tart', 22:39:15 INFO - 'tscrollx', 22:39:15 INFO - 'cart']}, 22:39:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'talos_options': ['--disable-e10s'], 22:39:15 INFO - 'tests': ['tp5o']}, 22:39:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'tests': ['tp5o']}, 22:39:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'talos_options': ['--disable-e10s', 22:39:15 INFO - '--xperf_path', 22:39:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:15 INFO - 'tests': ['tp5n']}, 22:39:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:15 INFO - 'talos_options': ['--xperf_path', 22:39:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:15 INFO - 'tests': ['tp5n']}}, 22:39:15 INFO - 'talos.zip': {'path': '', 22:39:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:39:15 INFO - Running post-action listener: _resource_record_post_action 22:39:15 INFO - [mozharness: 2017-01-02 06:39:15.429622Z] Finished populate-webroot step (success) 22:39:15 INFO - [mozharness: 2017-01-02 06:39:15.429749Z] Running create-virtualenv step. 22:39:15 INFO - Running pre-action listener: _resource_record_pre_action 22:39:15 INFO - Running main action method: create_virtualenv 22:39:15 INFO - Creating virtualenv /builds/slave/test/build/venv 22:39:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:39:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:39:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:39:15 INFO - Using real prefix '/tools/python27' 22:39:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:39:16 INFO - Installing distribute.............................................................................................................................................................................................done. 22:39:19 INFO - Installing pip.................done. 22:39:19 INFO - Return code: 0 22:39:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:39:19 INFO - Reading from file tmpfile_stdout 22:39:19 INFO - Output received: 22:39:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:39:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39: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', 'pip>=1.5'] in /builds/slave/test/build 22:39: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 pip>=1.5 22:39:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:19 INFO - 'HOME': '/Users/cltbld', 22:39:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:19 INFO - 'LOGNAME': 'cltbld', 22:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:19 INFO - 'MOZ_NO_REMOTE': '1', 22:39:19 INFO - 'NO_EM_RESTART': '1', 22:39:19 INFO - 'PAGER': '/bin/cat', 22:39:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:19 INFO - 'PWD': '/builds/slave/test', 22:39:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:19 INFO - 'SHELL': '/bin/bash', 22:39:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:19 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:19 INFO - 'USER': 'cltbld', 22:39:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:19 INFO - 'XPC_FLAGS': '0x0', 22:39:19 INFO - 'XPC_SERVICE_NAME': '0', 22:39:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:39:19 INFO - Cleaning up... 22:39:19 INFO - Return code: 0 22:39:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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', '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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:39: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 psutil>=3.1.1 22:39:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:19 INFO - 'HOME': '/Users/cltbld', 22:39:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:19 INFO - 'LOGNAME': 'cltbld', 22:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:19 INFO - 'MOZ_NO_REMOTE': '1', 22:39:19 INFO - 'NO_EM_RESTART': '1', 22:39:19 INFO - 'PAGER': '/bin/cat', 22:39:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:19 INFO - 'PWD': '/builds/slave/test', 22:39:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:19 INFO - 'SHELL': '/bin/bash', 22:39:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:19 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:19 INFO - 'USER': 'cltbld', 22:39:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:19 INFO - 'XPC_FLAGS': '0x0', 22:39:19 INFO - 'XPC_SERVICE_NAME': '0', 22:39:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:19 INFO - Downloading/unpacking psutil>=3.1.1 22:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:39:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:24 INFO - Installing collected packages: psutil 22:39:24 INFO - Running setup.py install for psutil 22:39:24 INFO - building 'psutil._psutil_osx' extension 22:39:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:39:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:39:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:39:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:39:24 INFO - building 'psutil._psutil_posix' extension 22:39:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:39:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:39:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:39:24 INFO - ^ 22:39:24 INFO - 1 warning generated. 22:39:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:39:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:24 INFO - Successfully installed psutil 22:39:24 INFO - Cleaning up... 22:39:24 INFO - Return code: 0 22:39:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:39: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')]} 22:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:24 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:24 INFO - 'HOME': '/Users/cltbld', 22:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:24 INFO - 'LOGNAME': 'cltbld', 22:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:24 INFO - 'MOZ_NO_REMOTE': '1', 22:39:24 INFO - 'NO_EM_RESTART': '1', 22:39:24 INFO - 'PAGER': '/bin/cat', 22:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:24 INFO - 'PWD': '/builds/slave/test', 22:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:24 INFO - 'SHELL': '/bin/bash', 22:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:24 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:24 INFO - 'USER': 'cltbld', 22:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:24 INFO - 'XPC_FLAGS': '0x0', 22:39:24 INFO - 'XPC_SERVICE_NAME': '0', 22:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:39:27 INFO - Installing collected packages: mozsystemmonitor 22:39:27 INFO - Running setup.py install for mozsystemmonitor 22:39:28 INFO - Successfully installed mozsystemmonitor 22:39:28 INFO - Cleaning up... 22:39:28 INFO - Return code: 0 22:39:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:28 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:28 INFO - 'HOME': '/Users/cltbld', 22:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:28 INFO - 'LOGNAME': 'cltbld', 22:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:28 INFO - 'MOZ_NO_REMOTE': '1', 22:39:28 INFO - 'NO_EM_RESTART': '1', 22:39:28 INFO - 'PAGER': '/bin/cat', 22:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:28 INFO - 'PWD': '/builds/slave/test', 22:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:28 INFO - 'SHELL': '/bin/bash', 22:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:28 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:28 INFO - 'USER': 'cltbld', 22:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:28 INFO - 'XPC_FLAGS': '0x0', 22:39:28 INFO - 'XPC_SERVICE_NAME': '0', 22:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:28 INFO - Downloading/unpacking jsonschema==2.5.1 22:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:39:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:31 INFO - Downloading functools32-3.2.3-2.tar.gz 22:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:39:31 INFO - warning: no files found matching '*.txt' 22:39:31 INFO - no previously-included directories found matching 'build' 22:39:31 INFO - no previously-included directories found matching 'dist' 22:39:31 INFO - no previously-included directories found matching '.git*' 22:39:31 INFO - Installing collected packages: jsonschema, functools32 22:39:31 INFO - Running setup.py install for functools32 22:39:31 INFO - warning: no files found matching '*.txt' 22:39:31 INFO - no previously-included directories found matching 'build' 22:39:31 INFO - no previously-included directories found matching 'dist' 22:39:31 INFO - no previously-included directories found matching '.git*' 22:39:31 INFO - Successfully installed jsonschema functools32 22:39:31 INFO - Cleaning up... 22:39:31 INFO - Return code: 0 22:39:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:31 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:31 INFO - 'HOME': '/Users/cltbld', 22:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:31 INFO - 'LOGNAME': 'cltbld', 22:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:31 INFO - 'MOZ_NO_REMOTE': '1', 22:39:31 INFO - 'NO_EM_RESTART': '1', 22:39:31 INFO - 'PAGER': '/bin/cat', 22:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:31 INFO - 'PWD': '/builds/slave/test', 22:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:31 INFO - 'SHELL': '/bin/bash', 22:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:31 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:31 INFO - 'USER': 'cltbld', 22:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:31 INFO - 'XPC_FLAGS': '0x0', 22:39:31 INFO - 'XPC_SERVICE_NAME': '0', 22:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:39:32 INFO - Cleaning up... 22:39:32 INFO - Return code: 0 22:39:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:39:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:32 INFO - 'HOME': '/Users/cltbld', 22:39:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:32 INFO - 'LOGNAME': 'cltbld', 22:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:32 INFO - 'MOZ_NO_REMOTE': '1', 22:39:32 INFO - 'NO_EM_RESTART': '1', 22:39:32 INFO - 'PAGER': '/bin/cat', 22:39:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:32 INFO - 'PWD': '/builds/slave/test', 22:39:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:32 INFO - 'SHELL': '/bin/bash', 22:39:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:32 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:32 INFO - 'USER': 'cltbld', 22:39:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:32 INFO - 'XPC_FLAGS': '0x0', 22:39:32 INFO - 'XPC_SERVICE_NAME': '0', 22:39:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:32 INFO - Downloading/unpacking blobuploader==1.2.4 22:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:34 INFO - Downloading blobuploader-1.2.4.tar.gz 22:39:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:39:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:39:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:36 INFO - Downloading docopt-0.6.1.tar.gz 22:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:39:36 INFO - Installing collected packages: blobuploader, requests, docopt 22:39:36 INFO - Running setup.py install for blobuploader 22:39:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:39:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:39:36 INFO - Running setup.py install for requests 22:39:36 INFO - Running setup.py install for docopt 22:39:37 INFO - Successfully installed blobuploader requests docopt 22:39:37 INFO - Cleaning up... 22:39:37 INFO - Return code: 0 22:39:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:37 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:39:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:37 INFO - 'HOME': '/Users/cltbld', 22:39:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:37 INFO - 'LOGNAME': 'cltbld', 22:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:37 INFO - 'MOZ_NO_REMOTE': '1', 22:39:37 INFO - 'NO_EM_RESTART': '1', 22:39:37 INFO - 'PAGER': '/bin/cat', 22:39:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:37 INFO - 'PWD': '/builds/slave/test', 22:39:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:37 INFO - 'SHELL': '/bin/bash', 22:39:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:37 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:37 INFO - 'USER': 'cltbld', 22:39:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:37 INFO - 'XPC_FLAGS': '0x0', 22:39:37 INFO - 'XPC_SERVICE_NAME': '0', 22:39:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:39:37 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-B9tswf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:39:37 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-SGB3yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:39:37 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-yEZFlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:39:37 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-6UHXRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:39:37 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-QpSPc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-wwo8Bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-hEm9LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8dLIgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-crt8kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-rZd_pE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-n1E7FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-drH9iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8MJUvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:39:38 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-70oyqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:39 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-xzwOZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:39:39 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-88gtYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:39:39 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-s0Rq6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:39:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:39:39 INFO - Running setup.py install for manifestparser 22:39:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:39:39 INFO - Running setup.py install for mozcrash 22:39:39 INFO - Running setup.py install for mozdebug 22:39:39 INFO - Running setup.py install for mozdevice 22:39:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:39:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:39:39 INFO - Running setup.py install for mozfile 22:39:40 INFO - Running setup.py install for mozhttpd 22:39:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Running setup.py install for mozinfo 22:39:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Running setup.py install for mozInstall 22:39:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Running setup.py install for mozleak 22:39:40 INFO - Running setup.py install for mozlog 22:39:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:39:40 INFO - Running setup.py install for moznetwork 22:39:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Running setup.py install for mozprocess 22:39:41 INFO - Running setup.py install for mozprofile 22:39:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Running setup.py install for mozrunner 22:39:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Running setup.py install for mozscreenshot 22:39:41 INFO - Running setup.py install for moztest 22:39:41 INFO - Running setup.py install for mozversion 22:39:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:39:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:39:41 INFO - Cleaning up... 22:39:41 INFO - Return code: 0 22:39:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:41 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:41 INFO - 'HOME': '/Users/cltbld', 22:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:41 INFO - 'LOGNAME': 'cltbld', 22:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:41 INFO - 'MOZ_NO_REMOTE': '1', 22:39:41 INFO - 'NO_EM_RESTART': '1', 22:39:41 INFO - 'PAGER': '/bin/cat', 22:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:41 INFO - 'PWD': '/builds/slave/test', 22:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:41 INFO - 'SHELL': '/bin/bash', 22:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:41 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:41 INFO - 'USER': 'cltbld', 22:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:41 INFO - 'XPC_FLAGS': '0x0', 22:39:41 INFO - 'XPC_SERVICE_NAME': '0', 22:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-4EE0w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-tUIgF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-hGsSlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8cQb4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-TyhqN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-4eli66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-eNWCGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:39:42 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-P2TsHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-ppMsm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-BHwWb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-NCd35d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-_qOumX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-B_AXYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-LX90cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-da7i8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Gatk2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:39:43 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-KfuAOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:39:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:46 INFO - Downloading blessings-1.6.tar.gz 22:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:39:46 INFO - Installing collected packages: blessings 22:39:46 INFO - Running setup.py install for blessings 22:39:46 INFO - Successfully installed blessings 22:39:46 INFO - Cleaning up... 22:39:47 INFO - Return code: 0 22:39:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:39:47 INFO - Reading from file tmpfile_stdout 22:39:47 INFO - Current package versions: 22:39:47 INFO - blessings == 1.6 22:39:47 INFO - blobuploader == 1.2.4 22:39:47 INFO - docopt == 0.6.1 22:39:47 INFO - functools32 == 3.2.3-2 22:39:47 INFO - jsonschema == 2.5.1 22:39:47 INFO - manifestparser == 1.1 22:39:47 INFO - mozInstall == 1.12 22:39:47 INFO - mozcrash == 1.0 22:39:47 INFO - mozdebug == 0.1 22:39:47 INFO - mozdevice == 0.48 22:39:47 INFO - mozfile == 1.2 22:39:47 INFO - mozhttpd == 0.7 22:39:47 INFO - mozinfo == 0.9 22:39:47 INFO - mozleak == 0.1 22:39:47 INFO - mozlog == 3.4 22:39:47 INFO - moznetwork == 0.27 22:39:47 INFO - mozprocess == 0.23 22:39:47 INFO - mozprofile == 0.28 22:39:47 INFO - mozrunner == 6.13 22:39:47 INFO - mozscreenshot == 0.1 22:39:47 INFO - mozsystemmonitor == 0.3 22:39:47 INFO - moztest == 0.7 22:39:47 INFO - mozversion == 1.4 22:39:47 INFO - psutil == 3.1.1 22:39:47 INFO - requests == 1.2.3 22:39:47 INFO - wsgiref == 0.1.2 22:39:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:47 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:39:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:47 INFO - 'HOME': '/Users/cltbld', 22:39:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:47 INFO - 'LOGNAME': 'cltbld', 22:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:47 INFO - 'MOZ_NO_REMOTE': '1', 22:39:47 INFO - 'NO_EM_RESTART': '1', 22:39:47 INFO - 'PAGER': '/bin/cat', 22:39:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:47 INFO - 'PWD': '/builds/slave/test', 22:39:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:47 INFO - 'SHELL': '/bin/bash', 22:39:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:47 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:47 INFO - 'USER': 'cltbld', 22:39:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:47 INFO - 'XPC_FLAGS': '0x0', 22:39:47 INFO - 'XPC_SERVICE_NAME': '0', 22:39:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:39:47 INFO - Cleaning up... 22:39:47 INFO - Return code: 0 22:39:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:47 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 0x1021af490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102125570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeaa5a5b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, '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.d0RaYfRT53/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.Ws24tExRs9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:39:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:39:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:39:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:39:47 INFO - 'HOME': '/Users/cltbld', 22:39:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:39:47 INFO - 'LOGNAME': 'cltbld', 22:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:47 INFO - 'MOZ_NO_REMOTE': '1', 22:39:47 INFO - 'NO_EM_RESTART': '1', 22:39:47 INFO - 'PAGER': '/bin/cat', 22:39:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:47 INFO - 'PWD': '/builds/slave/test', 22:39:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:39:47 INFO - 'SHELL': '/bin/bash', 22:39:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:39:47 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:39:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:39:47 INFO - 'USER': 'cltbld', 22:39:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:39:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:47 INFO - 'XPC_FLAGS': '0x0', 22:39:47 INFO - 'XPC_SERVICE_NAME': '0', 22:39:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:39:47 INFO - Cleaning up... 22:39:47 INFO - Return code: 0 22:39:47 INFO - Running post-action listener: _resource_record_post_action 22:39:47 INFO - Running post-action listener: _start_resource_monitoring 22:39:47 INFO - Starting resource monitoring. 22:39:47 INFO - [mozharness: 2017-01-02 06:39:47.890673Z] Finished create-virtualenv step (success) 22:39:47 INFO - [mozharness: 2017-01-02 06:39:47.891700Z] Running install step. 22:39:47 INFO - Running pre-action listener: _resource_record_pre_action 22:39:47 INFO - Running main action method: install 22:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:39:48 INFO - Reading from file tmpfile_stdout 22:39:48 INFO - Detecting whether we're running mozinstall >=1.0... 22:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:39:48 INFO - Reading from file tmpfile_stdout 22:39:48 INFO - Output received: 22:39:48 INFO - Usage: mozinstall [options] installer 22:39:48 INFO - Options: 22:39:48 INFO - -h, --help show this help message and exit 22:39:48 INFO - -d DEST, --destination=DEST 22:39:48 INFO - Directory to install application into. [default: 22:39:48 INFO - "/builds/slave/test"] 22:39:48 INFO - --app=APP Application being installed. [default: firefox] 22:39:48 INFO - mkdir: /builds/slave/test/build/application 22:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:40:19 INFO - Reading from file tmpfile_stdout 22:40:19 INFO - Output received: 22:40:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:40:19 INFO - Running post-action listener: _resource_record_post_action 22:40:19 INFO - [mozharness: 2017-01-02 06:40:19.515729Z] Finished install step (success) 22:40:19 INFO - [mozharness: 2017-01-02 06:40:19.515879Z] Running run-tests step. 22:40:19 INFO - Running pre-action listener: _resource_record_pre_action 22:40:19 INFO - Running main action method: run_tests 22:40:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:40:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:40:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:40:19 INFO - 2017-01-01 22:40:19.530 screenresolution[2485:85331] starting screenresolution argv=screenresolution get 22:40:19 INFO - 2017-01-01 22:40:19.533 screenresolution[2485:85331] Display 0: 1600x1200x32@60 22:40:19 INFO - 2017-01-01 22:40:19.539 screenresolution[2486:85339] starting screenresolution argv=screenresolution list 22:40:19 INFO - Available Modes on Display 0 22:40:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:40:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:40:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:40:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:40:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:40:19 INFO - Intel Iris: 22:40:19 INFO - Chipset Model: Intel Iris 22:40:19 INFO - Type: GPU 22:40:19 INFO - Bus: Built-In 22:40:19 INFO - VRAM (Dynamic, Max): 1536 MB 22:40:19 INFO - Vendor: Intel (0x8086) 22:40:19 INFO - Device ID: 0x0a2e 22:40:19 INFO - Revision ID: 0x0009 22:40:19 INFO - Displays: 22:40:19 INFO - Display: 22:40:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:40:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:40:19 INFO - Display Serial Number: 1600x1200 60 22:40:19 INFO - Main Display: Yes 22:40:19 INFO - Mirror: Off 22:40:19 INFO - Online: Yes 22:40:19 INFO - Rotation: Supported 22:40:19 INFO - Return code: 0 22:40:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:40:19 INFO - Python 2.7.3 22:40:19 INFO - Return code: 0 22:40:19 INFO - grabbing minidump binary from tooltool 22:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:40:19 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 0x101fc3d30>, '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 0x101e29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101fbbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:40:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:40:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:40:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:40:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:40:19 INFO - Return code: 0 22:40:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:40:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:40:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:40:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:40:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:40:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 22:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0362 --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 22:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d0RaYfRT53/Render', 22:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:40:19 INFO - 'HOME': '/Users/cltbld', 22:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:40:19 INFO - 'LOGNAME': 'cltbld', 22:40:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:40:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:19 INFO - 'MOZ_NO_REMOTE': '1', 22:40:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:40:19 INFO - 'NO_EM_RESTART': '1', 22:40:19 INFO - 'PAGER': '/bin/cat', 22:40:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:19 INFO - 'PWD': '/builds/slave/test', 22:40:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:40:19 INFO - 'SHELL': '/bin/bash', 22:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ws24tExRs9/Listeners', 22:40:19 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 22:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:40:19 INFO - 'USER': 'cltbld', 22:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:19 INFO - 'XPC_FLAGS': '0x0', 22:40:19 INFO - 'XPC_SERVICE_NAME': '0', 22:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:40:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 22:40:20 INFO - mozversion application_buildid: 20170101194120 22:40:20 INFO - mozversion application_changeset: 1458ff2725619c3db4b1dcb4f057f26a60208873 22:40:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:40:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:40:20 INFO - mozversion application_name: Firefox 22:40:20 INFO - mozversion application_remotingname: firefox-dev 22:40:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:40:20 INFO - mozversion application_vendor: Mozilla 22:40:20 INFO - mozversion application_version: 52.0a2 22:40:20 INFO - mozversion platform_buildid: 20170101194120 22:40:20 INFO - mozversion platform_changeset: 1458ff2725619c3db4b1dcb4f057f26a60208873 22:40:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:40:20 INFO - mozversion platform_version: 52.0a2 22:40:20 INFO - using testdate: 1483339220 22:40:20 INFO - actual date: 1483339220 22:40:20 INFO - starting webserver on 'localhost:63507' 22:40:20 INFO - SUITE-START | Running 2 tests 22:40:20 INFO - TEST-START | dromaeo_css 22:40:20 INFO - operating with platform_type : mac_ 22:40:20 INFO - Initialising browser for dromaeo_css test... 22:40:20 INFO - TEST-INFO | started process 2496 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpry66iF/profile http://localhost:63507/getInfo.html) 22:40:22 INFO - PROCESS | 2496 | __metrics Screen width/height:1600/1200 22:40:22 INFO - PROCESS | 2496 | colorDepth:24 22:40:22 INFO - PROCESS | 2496 | Browser inner width/height: 1024/689 22:40:22 INFO - PROCESS | 2496 | __metrics 22:40:22 INFO - PROCESS | 2496 | 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" 22:40:22 INFO - PROCESS | 2496 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:40:23 INFO - TEST-INFO | 2496: exit 0 22:40:23 INFO - Browser initialized. 22:40:23 INFO - Running cycle 1/1 for dromaeo_css test... 22:40:23 INFO - TEST-INFO | started process 2498 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpry66iF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:35 INFO - PROCESS | 2498 | RSS: Main: 222543872 22:40:35 INFO - PROCESS | 2498 | 22:41:26 INFO - PROCESS | 2498 | 1483339286832 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:43:40 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63507/tests/dromaeo/cssquery-ext.html) 22:43:42 INFO - PROCESS | 2498 | RSS: Main: 396722176 22:43:42 INFO - PROCESS | 2498 | 22:46:45 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-ext.html (next: http://localhost:63507/tests/dromaeo/cssquery-jquery.html) 22:46:46 INFO - PROCESS | 2498 | RSS: Main: 348700672 22:46:46 INFO - PROCESS | 2498 | 22:49:48 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63507/tests/dromaeo/cssquery-mootools.html) 22:49:49 INFO - PROCESS | 2498 | RSS: Main: 331948032 22:49:49 INFO - PROCESS | 2498 | 22:52:51 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63507/tests/dromaeo/cssquery-prototype.html) 22:52:52 INFO - PROCESS | 2498 | RSS: Main: 324239360 22:52:52 INFO - PROCESS | 2498 | 22:55:55 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63507/tests/dromaeo/cssquery-yui.html) 22:55:56 INFO - PROCESS | 2498 | RSS: Main: 312377344 22:55:56 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | Cycle 1(1): loaded http://localhost:63507/tests/dromaeo/cssquery-yui.html (next: http://localhost:63507/tests/dromaeo/cssquery-dojo.html) 22:58:59 INFO - PROCESS | 2498 | RSS: Main: 375578624 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | __start_tp_report 22:58:59 INFO - PROCESS | 2498 | _x_x_mozilla_page_load 22:58:59 INFO - PROCESS | 2498 | _x_x_mozilla_page_load_details 22:58:59 INFO - PROCESS | 2498 | |i|pagename|runs| 22:58:59 INFO - PROCESS | 2498 | |0;dojo.html;2613.6732908386452;3294.8352582370876;3307.61962374327;3309.8179600121985;3370.2080011197363;5600.859978500534;5680.9715951420285;5693.914591281134;5712.086066229404;5735.168400581908;5621.269234999455;5703.77184912603;5707.743151558179;5719.313682358123;5765.1352297155345;5122.487751224876;5173.094708426029;5181.1969144782615;5190.6107041972;5214.791408343681;5122.103631864427;5144.022635699205;5174.042802081627;5315.0668505306185;5326.2543243945875;5267.76295066721;5274.945010997816;5289.206619007141;5289.735513224324;5295.894082118374;5262.210668399752;5277.525022747933;5279.524842764151;5281.102212623863;5285.524302812765;5112.46319136489;5228.92156617651;5232.58139348855;5236.450172731885;5268.972550352682;5785.27684039495;5789.855253618651;5794.985877471426;5799.536037117063;5800.593958422912;6104.76233045773;6136.059583474755;6138.830475372222;6140.486742244447;6148.38516148386;6169.876602467971;6230.283517395467;6235.438810507031;6242.625442473466;6279.026750853625;4814.879628009293;5740;5783.5951483396175;5790.334111577138;5802.245708057968;6249.78125765596;6309.3690630936535;6326.114343991845;6327.303996560375;6332.430081292614;6012.909806352908;6025.4577088061405;6084.117802918552;6087;6096.268447786205;5682.488576028178;5836.970815145897;6273.1844860167885;6287.088372186007;6318.336574659686;6187.876242475125;6228.34602366754;6236.126942228092;6241.095041218998;6242.063690446469;5284.128118860345;5908.936391449581;6003;6008.969955150196;6042.909356359658;4931.914978704679;4945.986072855128;4954.40547134339;4957.3307603473795;4984.825531106394;4439.62263207631;4446.844360447369;4453.020335526179;4464.129494748493;4471.977640111843;5591.41290164535;5618.073017951992;5675.914861277084;5755.884882302331;5775.27809023872;6175.073738939105;6202.100695399232;6209.161763162006;6218.968905155445;6234.220722409699;5785.929603023455;5807.448292412214;5810.82567522975;5815.767369305265;5829.912551311733;5395;5439.619226654097;5450.291462109901;5451.209574611738;5463.672179669233;6339.429451349309;6441.13044738973;6453.064305675557;6458.031295305742;6463.385978332145;6136.23297087864;6166.630002199882;6170.290416602148;6233.127362169209;6277.2153480814895;6362.395572420613;6367.808965731036;6411.487081033413;6421.261554921244;6431.099646049463;6264.59280146789;6272.4982001440685;6272.560920735505;6279.089532017923;6280.7487700491465;6035.215421995288;6064.787732429344;6113.29697084841;6138.020573254662;6282.8115156543545;6038.63768173911;6059.242594675666;6138.817583427312;6140.228185505611;6148.78479253224;5916.112583112567;6044.969775151272;6071.241094863142;6090.086487026982;6100.877982440237;5752.597318187687;5847.970760146342;5916.55625828056;5969.910451343147;5971.671558064174;5952.434518720715;6005.489533389536;6024.849378765566;6025.517958563217;6029;5870.618409803349;5876.706164691663;5887.52899768009;5889.941100589111;5893.174955506147 22:58:59 INFO - PROCESS | 2498 | |1;ext.html;501.686445971268;509.23360342684254;509.9184130539114;515.3635260453342;537.6290359651838;27689;29258.268543286402;39399.80300098495;40682.79658601703;40886.59113408865;42099.57900420995;42535.14929701412;42567.57432425675;42628.3605745914;42629.78685106578;9335.533223338805;9353.579088941015;9498.952505237465;9544.713658590219;9587.23302135831;8498.065212826585;8759.649614015432;11080;11419.54321827126;11428.314301141958;11284.379359135244;11328.716782080473;11336.979671829533;11339.433028348549;11379.317240965527;8505;11379.487923043442;11444.027257683148;11457.484413201426;11465.31208127515;8551.059383467813;10985;11363.829542556869;11388.14588905827;11505.194636375458;32219.51670724941;39866;40116.398254026215;40143;40149.99625009369;3226.6128064632157;3284.1132894118455;3462.2556150427527;3488.778927375424;3822.9808850955847;28262.717372826213;28336;29397.85301073481;29737.70262297371;29973.950911717984;3093.2266933266683;3095.133362658459;3322.435186018383;3322.9169270768184;3324.1855745342305;356002;357515.9999999974;359742;359953;360685;58231.4176858226;58747.41252587505;58816.41183588195;58818.41181588215;58869;62882.68558657269;63075;63091;63106.368936310966;63170.36829631737;15806.60483487922;15995.760063599055;16032.198390080683;16033.599160021093;16049.438269660739;11167.776644467227;11439.256448330832;12411;12425.689357765947;12461.127721059438;14788.408463661548;14891.404343826342;14894.404223831141;14900.329485173193;14922.40310387594;6324.525660575475;6856.828579285558;8001.679932802739;8062.355011599058;8111.229433203836;813.7762115418308;933.09955892564;984.6750572311263;1026.2816028779885;1030.299396410448;28976.565351519745;29936.700632993827;29990.95031673881;30462.933797316986;30480.54279185814;984.0503913723292;1031.9380837149795;1034.4879284754095;1048.491481631408;1066.4507778494083;15108.39566417331;15376.692466150615;15446.691066178839;15466.5360039199;15808.52574422769;706291;712247;712677;713513;713669;57333.71333143308;57750;57767.711161444764;57770;58012.41987580155;1206.4570943075475;1257.5032862019634;1258.282778816066;1348.6426097084282;1379.5033787836371;24186.51626967486;26076;26132.8693356532;31149;31154;55013;55355.72322138525;55765.72117139388;55777.44222557722;55813;638591;642279;643969.9007524662;644160;644668;26712;27117.186484405498;27690.723092768814;42280.154396911275;42952.57047429611;43126.78436607922;45555.088898222515;46115.3082703753;46534.30198547092;46803.063938720356;36930.815345923096;37092.44361334636;37111.628883710815;37290;38144.80927595344;36784.26431471409;36922.26155476929;37458;37968.24063518769;37997.81001094977;37116.072098197765;37821;37856.053598660255;37957;38154.236915262096;31116.844415777778;32859.178520536225;33130.17174570655;33213;33374.833125834215;24928.626070608592;25119.74880251174;25253;25325.493490130462;25909.22272331833 22:58:59 INFO - PROCESS | 2498 | |2;jquery.html;346.9236767911059;346.9739769517285;359.36213221531784;369.51777929801597;374.41778035155323;14560.635984100403;14787.186704731235;15689.686206275883;16358.591035224126;20422.489437764063;19037.809621903776;19567.510812229702;20036.39890803278;20320.89839550804;20349;5748.5688573357065;6061.12113743507;6069.939300607004;6079.452849243589;6085.178500902388;5649.49154576088;5922.822315330547;5962.986292330314;5973.88052238955;6006.669633170174;5928.3775203603645;5929.229200203989;5936.970315148418;5942.702864856761;5947.88104237915;5640.689762063085;5860.765569377241;5926.318473375574;5928.49607783339;5932.911006334908;4944.258361245842;5180.119379705459;5184.844454666366;5185.870353241162;5187.870303242411;24512.387190320205;24779;24787.25638230856;24825.50348993028;25134.371640708945;2412.1075202175307;2456.238566044514;2497.500499900027;2532.2909585316143;2886.3794284228993;15007.474738384104;15051.924740376338;15449.845501545065;15836.683266334609;16058.518244452685;1747.3360123153248;1817.5183576352276;1819.1540933466001;1869.7382366468705;1881.3791448821855;117366.82633173729;117934;120969.39515302367;121899;123738.38130809378;8797.604107815036;10512.57949682004;10921.39932303715;11186.38474883873;11462;13539.187648741106;13601.455941762219;13616.86383136156;13617.523386681418;13673.726525469436;8035.316998055231;8241.464304820167;8274.29668478174;8355.289800366916;8446.788830279293;8283.08886022537;8295.050663094167;8328.875066874001;8338.124496927856;8497.490150590984;5412.945870541244;6463.7091330890225;6542.869142617121;6555.475561955032;6703.262641109474;1516.6132636177704;1750.361118191851;1792.4353828543967;1792.507060558357;1795.5511122219445;876.2289185516831;935.9532023398802;963.7686955130719;1005.3666190300064;1027.2398425165325;21478.677819832716;22082.668759968612;22219.888900555394;22268.66597001046;22296.442588935406;858.6350800909588;883.9558022098869;918.8989211186764;977.1742877268697;1016.4511163971521;11076.612318568972;11557.190996630155;11645.301281923112;11646.184767066394;11682.76634467323;601896;616070;617818;629401;632532;104669;112105.87894121118;112465;114649;121873.39063304626;811.1563973467646;992.5880759484733;1004.1866088468219;1005.8692369991854;1008.2438171371472;14895.584919432637;18894.96077715739;19371.903140484206;19969.700454493486;20098.995050247137;51889;53465.18813306821;53935.46064539305;54548;55457.1681424787;69454.65272673806;70662.64668676624;73303.63348183225;75221;75965;8072.112067672434;8119.634616442392;8298.170182981654;8348.833023339377;8412.844672987874;10126.240531959986;12477.688057798629;12529.874701252871;12913.289769062416;12935.547255846377;10356.585736570452;10622.256442048982;10804.783904321712;10967.067799237155;11128.666140015812;10761.784764304826;10906.945465272624;10940.890591093987;11289.830652540055;11299.548018079186;10877.238593298393;11064.889351106385;11138.888611113785;11235.943820280847;11446;8100.35197184212;8152.429329946846;8152.877706834283;8303.958480207802;8344.332453403835;9227.492487913229;11001.941108078252;11244.66266012021;11269.943650282024;11295.943520282675 22:58:59 INFO - PROCESS | 2498 | |3;mootools.html;1449.949251776188;1460.8247010358755;1469.8162729658793;1780.866435017374;1788.6601545706324;8044.6379912903985;8497.447665901713;8520.786980325494;8520.892284003086;8521.616527256272;7422.035135432387;7696.653650585723;7712.96143519283;8037.156098609649;8321.833563328744;2308.9538209235807;2349.694539709835;2350.788429041386;2357.49313897512;2357.610994185966;2345.8006069484113;2371.1819422299277;2391.35433432973;2399.592069348215;2402;2318.652202169671;2328.1036800831716;2346.5893468643003;2351.7413084560685;2357.387079359362;2292.770722927694;2293.5298263855952;2316.339843144696;2317.1194945920442;2323.4423738302853;2336.380859072347;2362.4684445999683;2364.0898254172093;2370.9804783942895;2371.905123795046;8242.340612750966;8409.453385529921;8415.03227128882;8441.662333506652;8443;5063.341765570489;5229.895402091937;5229.895402091975;5248.81629142982;5255.6583822051825;2742.616033755276;3368.1411240133607;3593.964060359389;3602.495650608917;3607.134287770942;1033.1889466768625;1037.1391744851755;1059.8622179116742;1097.8078836203633;1117.5473933057044;10943;10991.175661825315;11151.832722509169;11159.609413670563;11176.832347514794;4540.72755634663;4607.631389488833;4738.905221895612;4749.430068391815;4793.257045158006;9542.379745316532;9555.713328600154;9563.856542151734;9574.808503829885;9586.760330991781;5245.868853278622;5248.475152484721;5249.7112658804135;5250.894982100336;5263.5525980292105;4036.616521430459;4120.25835349634;4787.880302992384;4799.424069111729;4802.8799280018275;3816.904577385588;3830.5594856591847;3898.161895192547;3934.3311637021775;3947.703922205789;1074.9140068794477;1248.5005997601031;1254.8494180698374;1270.9364531773374;1274.6048724895313;757.2049348184495;869.4348673362239;876.4040452492239;904.0868722590232;908.1599520443564;5461.208124821878;5742.684152371659;5872.530197584183;5909.527237820964;5981.132735753292;866.2117473099449;890.1632465482555;894.2979760887672;903.8418276801533;920.0798260861022;3585.30086633104;3837.6737977271687;3855.4024126260474;3856.633619806114;3937.7243592948794;8945.507997060095;9799.657012004545;10075;10219.48902554884;10366.948165259126;9712.22302215821;10317.896821031694;10329.793404131875;10330.173586113093;10336.0697537222;6773.830654233683;7559.3196612304055;7982.840343193104;8000.63997120131;8006.3995200360205;5717.656940583578;5800.216970708901;5811.94188058114;5986.371430999682;6031.819045428644;9378.296627753083;9578.185854202471;9600.85598716006;9613.759656008655;9625.614975401186;9306.72079837606;9623.518824058629;9638.807223855623;9863.457509837028;9874.061964113398;6133.938660613515;6710.597364158166;6933.5493192942295;6938.271481494572;7405.370543503863;7774.494657847222;7880.960595196987;8051.315638170821;8130.349572034342;8135.471194372347;7013.123359580052;7159.892601610876;7165.534240274365;7220.783376498714;7298.4891057625455;7009.719611215494;7054.540013466629;7075.363217310467;7200.9639951802;7290.927090729025;6587.9670601646685;6611.2727599963;6620.8344791380605;6684.030815531623;6711.530192886451;4760.97619511914;4784.95215047859;4836.322914791862;4836.564709176191;4887.682300650446;4752.857414277577;4839.903201936012;4889.193283108248;4966.553010229097;4975.776090076028 22:58:59 INFO - PROCESS | 2498 | |4;prototype.html;298.67146139246825;301.1988111623082;307.5555821837445;311.4953774884687;312.7654259305521;5064.56951159152;5271.894562108756;5297.07301222286;5319.547838433733;5324.056719297458;5020.3724534433195;5156.020356132332;5160.612954028454;5211.609129315298;5255.372313843079;4004.1190937993597;4030.7984600769846;4057.756534607933;4062.5531191568903;4064.7602481243207;3743.86896458624;3952;4003.279409706266;4102.087517862321;4143.544210136897;3383.9154021149543;3945.842166313344;3962.603739626043;3968.1270120573427;3978.781167035812;3949.6840252779707;3968.7179871573962;3973.9534139917964;3981.880543583697;3986.7807270600106;3330.333933213343;3416.7437442191936;3430.9656903430896;3489.557309883092;3492.388831954398;4581.060882519076;4891.972685736;5038.949610503922;5057.418396884332;5129.461406552333;4014.879553613396;4305.203537345601;4410.691251612388;4499.550044995507;4513.909721805579;5368.597355198376;5432.538234250093;5460.34475862895;5472.616916815825;5500.339959204881;3739.121306492972;3758.3798673218885;3760.5487341519192;3793.823914586487;3802.5056742623283;5133.0503856786245;5472.288602481652;5719.656820590736;5781;5826.650400975955;3561.7684850484634;3764.134249122717;3797.6582107610448;3827.234553089393;3849.9807500962315;3853.9421908671393;3860;3863.3194560366333;3872.341701910682;3894.805259737002;4014.1770936957855;4101;4229.936550951738;4482.8206871725415;4505.346724724896;4596.862094137181;5111.95521791279;5414.945850541523;5522.3649280333275;5559.861003474945;1077.5205033760058;1141.7887690777234;1244.539520377466;1247.2765795838393;1253.486070711004;756.8070142113836;838.4466252273592;850.4939560961218;850.9914900851036;853.7396094191331;4798.936457323314;4805.639577031667;4809.687370320988;4811.350467686888;4835.734034628059;4693.389859318335;5245.527902488794;5246.816361427332;5290.735463226823;5364.597655175799;4235.449391579075;4352.69531132824;4799.544043315879;4821.156297647897;4844.927326090112;2349.036894873094;2505.837120587156;2515.421453065805;2742.835429874214;2786.275568352243;5243.580513558983;5331.733413329318;5503.284573005563;5536.363318218457;5663.858403539862;5016.849494515171;5413.891722165535;5419.756110975016;5422.457754224546;5423.891522169534;4841.685290456179;5103.795848166032;5241.9516096780035;5446.863828404243;5483.2323474712775;4479.46246450428;4536.9092618148115;5009.749512524432;5248.501392367872;5281.042616134977;4173.540910499782;4187.958120418757;4349.412829268008;4578.5650363215445;5240.947590524199;4908.7545622719435;5116.780883498885;5118.283440318284;5418.315545429146;5470.288862448016;2988.462076826192;3492.4062909305985;3591.6587924147166;3682.0359610512724;3776.206996530759;5148.330717006766;5587.552995760248;5621.943780562306;5623.959567480029;5627.606067575231;4244.469441319835;5034.546890779848;5457.20870473787;5485.3543936818;5589.385167631476;4585.770711464346;5154.634021984252;5497.471163264783;5536.785285885678;5585.832425027255;4752.952470475389;5218.817341393033;5414.8646283841645;5423.186522021729;5448;4727.858164254941;4895.779689914034;5483.297370734392;5528.834134975797;5642.379338272869;4548.998440686088;4665.463471700662;5374.731263436891;5412.945870541244;5438.891222175455 22:58:59 INFO - PROCESS | 2498 | |5;yui.html;673.9224989126251;705.7282946065764;718.809515478398;749.0861149397737;770.2720928722356;14070.859291407083;14835.480758173466;14877;14878.628034299149;14895.702085958274;12841.935790321058;12847.550335738251;12857.100002999794;12967.416466259017;13017.544385946494;638.097092613953;659.1134923527848;661.6592454885738;790.2255789326464;800.275750445846;801.8917446144783;806.6531391501652;829.9832704936434;830.0081402723747;830.3523251863556;875.9124087591254;877.4472082587961;879.4371602174614;879.9472031678088;883.0374891368407;624.9343818898996;641.4066988036049;642.097852517214;870.708312715238;874.2001069021837;488.6847983050948;488.89488759916435;526.9525742683177;640.4300172846124;641.2433328672165;14003.439862405594;14021.64945876351;14031.228282444461;14041.157530548098;14096.788548171786;12813.87186128136;13023.674408139777;13158.013149013863;13184.208947463087;13265.27041012744;11459.255148415326;11761.705957351049;11834.526618935231;12107.334096624681;12166.878331216663;4275.444192255018;4292.098659281554;4388.341748737683;4391.890202744957;4453.487848897354;69288.30711692868;72531.27468725298;72642;83873.3225335496;86353;10416.427096509613;10449.738756531147;10582.682519524427;10620.468976551141;10900;18597.34909278169;19581.804181958283;20139.39581812548;20291.797082028992;20345.982700865192;7733.265339792823;7801.882971755428;7837.216278372117;7885.1720569339395;8004.159563245776;4028.1742242840146;4151.8546850860075;4155.605217504332;4189.874303770892;4347.869563913087;3440.776349537272;3510.315488479722;3531.611522732497;3532.8586856525453;3563.6080031196548;639.229728177545;655.6099121022984;663.8705452436728;668.1180841289452;668.4785867023729;578.9913151302734;590.35356284868;607.1318015238252;613.5091926458815;633.9746410143634;8410.705625303084;8737.21365077146;8799.91200087991;8889.244414224733;9367.953160234156;493.3019777015483;515.3712470785636;643.829385212919;650.200253687957;656.8373978058809;3362.4956256561713;4272.252355837716;4321.416608757841;4489.349044388546;4512.503624601291;68319.65840170767;94128;98195;102645.46031809528;103767.96232037734;92290.07709922949;93426;93729.5313523428;94386;96036.51981740046;71845.6407717958;78806.21193788103;79396.60301698338;80759.59620201861;84931;8508.149185081442;8557.828843422973;8617.655293788179;8630.136986301319;8795.604197811243;52648.68378290573;52977;53234;53306.46693533015;53611;51658.48341516537;76737;78391.60804195942;79071.20928790639;79527.20472795429;10832.295900766425;11033;11174.88825111771;11305.886941130484;11573.247738896946;11965.162438629213;12051.156419050581;12100.555597080142;12295.44670489848;12419.813702794285;11377.772444551229;11884.58403955892;11889.34608596501;12040.638780836589;12425.689357766129;11818.321724841757;11881.5841445549;11927.76144477123;12401.13192076582;12416.006719462603;11629;11675.88324116782;11857.822132667845;12293.631191064282;12365.381730913597;9357.953210233905;9646.469444180637;10188.184945204513;10215.02957219063;10275.588976440858;9427.245820334494;9949.552270148006;9970.800583988135;9991.447551613055;10051.849222261526 22:58:59 INFO - PROCESS | 2498 | __end_tp_report 22:58:59 INFO - PROCESS | 2498 | __start_cc_report 22:58:59 INFO - PROCESS | 2498 | _x_x_mozilla_cycle_collect,2004 22:58:59 INFO - PROCESS | 2498 | __end_cc_report 22:58:59 INFO - PROCESS | 2498 | __startTimestamp1483340339774__endTimestamp 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | ------- Summary: start ------- 22:58:59 INFO - PROCESS | 2498 | Number of tests: 6 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#0] dojo.html Cycles:165 Average:5724.83 Median:5887.53 stddev:652.74 (11.1%) stddev-sans-first:607.41 22:58:59 INFO - PROCESS | 2498 | Values: 2613.7 3294.8 3307.6 3309.8 3370.2 5600.9 5681.0 5693.9 5712.1 5735.2 5621.3 5703.8 5707.7 5719.3 5765.1 5122.5 5173.1 5181.2 5190.6 5214.8 5122.1 5144.0 5174.0 5315.1 5326.3 5267.8 5274.9 5289.2 5289.7 5295.9 5262.2 5277.5 5279.5 5281.1 5285.5 5112.5 5228.9 5232.6 5236.5 5269.0 5785.3 5789.9 5795.0 5799.5 5800.6 6104.8 6136.1 6138.8 6140.5 6148.4 6169.9 6230.3 6235.4 6242.6 6279.0 4814.9 5740.0 5783.6 5790.3 5802.2 6249.8 6309.4 6326.1 6327.3 6332.4 6012.9 6025.5 6084.1 6087.0 6096.3 5682.5 5837.0 6273.2 6287.1 6318.3 6187.9 6228.3 6236.1 6241.1 6242.1 5284.1 5908.9 6003.0 6009.0 6042.9 4931.9 4946.0 4954.4 4957.3 4984.8 4439.6 4446.8 4453.0 4464.1 4472.0 5591.4 5618.1 5675.9 5755.9 5775.3 6175.1 6202.1 6209.2 6219.0 6234.2 5785.9 5807.4 5810.8 5815.8 5829.9 5395.0 5439.6 5450.3 5451.2 5463.7 6339.4 6441.1 6453.1 6458.0 6463.4 6136.2 6166.6 6170.3 6233.1 6277.2 6362.4 6367.8 6411.5 6421.3 6431.1 6264.6 6272.5 6272.6 6279.1 6280.7 6035.2 6064.8 6113.3 6138.0 6282.8 6038.6 6059.2 6138.8 6140.2 6148.8 5916.1 6045.0 6071.2 6090.1 6100.9 5752.6 5848.0 5916.6 5969.9 5971.7 5952.4 6005.5 6024.8 6025.5 6029.0 5870.6 5876.7 5887.5 5889.9 5893.2 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#1] ext.html Cycles:180 Average:70280.00 Median:360319.00 stddev:158746.18 (44.1%) stddev-sans-first:159105.06 22:58:59 INFO - PROCESS | 2498 | Values: 501.7 509.2 509.9 515.4 537.6 27689.0 29258.3 39399.8 40682.8 40886.6 42099.6 42535.1 42567.6 42628.4 42629.8 9335.5 9353.6 9499.0 9544.7 9587.2 8498.1 8759.6 11080.0 11419.5 11428.3 11284.4 11328.7 11337.0 11339.4 11379.3 8505.0 11379.5 11444.0 11457.5 11465.3 8551.1 10985.0 11363.8 11388.1 11505.2 32219.5 39866.0 40116.4 40143.0 40150.0 3226.6 3284.1 3462.3 3488.8 3823.0 28262.7 28336.0 29397.9 29737.7 29974.0 3093.2 3095.1 3322.4 3322.9 3324.2 356002.0 357516.0 359742.0 359953.0 360685.0 58231.4 58747.4 58816.4 58818.4 58869.0 62882.7 63075.0 63091.0 63106.4 63170.4 15806.6 15995.8 16032.2 16033.6 16049.4 11167.8 11439.3 12411.0 12425.7 12461.1 14788.4 14891.4 14894.4 14900.3 14922.4 6324.5 6856.8 8001.7 8062.4 8111.2 813.8 933.1 984.7 1026.3 1030.3 28976.6 29936.7 29991.0 30462.9 30480.5 984.1 1031.9 1034.5 1048.5 1066.5 15108.4 15376.7 15446.7 15466.5 15808.5 706291.0 712247.0 712677.0 713513.0 713669.0 57333.7 57750.0 57767.7 57770.0 58012.4 1206.5 1257.5 1258.3 1348.6 1379.5 24186.5 26076.0 26132.9 31149.0 31154.0 55013.0 55355.7 55765.7 55777.4 55813.0 638591.0 642279.0 643969.9 644160.0 644668.0 26712.0 27117.2 27690.7 42280.2 42952.6 43126.8 45555.1 46115.3 46534.3 46803.1 36930.8 37092.4 37111.6 37290.0 38144.8 36784.3 36922.3 37458.0 37968.2 37997.8 37116.1 37821.0 37856.1 37957.0 38154.2 31116.8 32859.2 33130.2 33213.0 33374.8 24928.6 25119.7 25253.0 25325.5 25909.2 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#2] jquery.html Cycles:180 Average:35176.59 Median:13484.31 stddev:102875.15 (762.9%) stddev-sans-first:103130.50 22:58:59 INFO - PROCESS | 2498 | Values: 346.9 347.0 359.4 369.5 374.4 14560.6 14787.2 15689.7 16358.6 20422.5 19037.8 19567.5 20036.4 20320.9 20349.0 5748.6 6061.1 6069.9 6079.5 6085.2 5649.5 5922.8 5963.0 5973.9 6006.7 5928.4 5929.2 5937.0 5942.7 5947.9 5640.7 5860.8 5926.3 5928.5 5932.9 4944.3 5180.1 5184.8 5185.9 5187.9 24512.4 24779.0 24787.3 24825.5 25134.4 2412.1 2456.2 2497.5 2532.3 2886.4 15007.5 15051.9 15449.8 15836.7 16058.5 1747.3 1817.5 1819.2 1869.7 1881.4 117366.8 117934.0 120969.4 121899.0 123738.4 8797.6 10512.6 10921.4 11186.4 11462.0 13539.2 13601.5 13616.9 13617.5 13673.7 8035.3 8241.5 8274.3 8355.3 8446.8 8283.1 8295.1 8328.9 8338.1 8497.5 5412.9 6463.7 6542.9 6555.5 6703.3 1516.6 1750.4 1792.4 1792.5 1795.6 876.2 936.0 963.8 1005.4 1027.2 21478.7 22082.7 22219.9 22268.7 22296.4 858.6 884.0 918.9 977.2 1016.5 11076.6 11557.2 11645.3 11646.2 11682.8 601896.0 616070.0 617818.0 629401.0 632532.0 104669.0 112105.9 112465.0 114649.0 121873.4 811.2 992.6 1004.2 1005.9 1008.2 14895.6 18895.0 19371.9 19969.7 20099.0 51889.0 53465.2 53935.5 54548.0 55457.2 69454.7 70662.6 73303.6 75221.0 75965.0 8072.1 8119.6 8298.2 8348.8 8412.8 10126.2 12477.7 12529.9 12913.3 12935.5 10356.6 10622.3 10804.8 10967.1 11128.7 10761.8 10906.9 10940.9 11289.8 11299.5 10877.2 11064.9 11138.9 11235.9 11446.0 8100.4 8152.4 8152.9 8304.0 8344.3 9227.5 11001.9 11244.7 11269.9 11295.9 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#3] mootools.html Cycles:180 Average:5486.50 Median:5247.17 stddev:3009.72 (57.4%) stddev-sans-first:3002.88 22:58:59 INFO - PROCESS | 2498 | Values: 1449.9 1460.8 1469.8 1780.9 1788.7 8044.6 8497.4 8520.8 8520.9 8521.6 7422.0 7696.7 7713.0 8037.2 8321.8 2309.0 2349.7 2350.8 2357.5 2357.6 2345.8 2371.2 2391.4 2399.6 2402.0 2318.7 2328.1 2346.6 2351.7 2357.4 2292.8 2293.5 2316.3 2317.1 2323.4 2336.4 2362.5 2364.1 2371.0 2371.9 8242.3 8409.5 8415.0 8441.7 8443.0 5063.3 5229.9 5229.9 5248.8 5255.7 2742.6 3368.1 3594.0 3602.5 3607.1 1033.2 1037.1 1059.9 1097.8 1117.5 10943.0 10991.2 11151.8 11159.6 11176.8 4540.7 4607.6 4738.9 4749.4 4793.3 9542.4 9555.7 9563.9 9574.8 9586.8 5245.9 5248.5 5249.7 5250.9 5263.6 4036.6 4120.3 4787.9 4799.4 4802.9 3816.9 3830.6 3898.2 3934.3 3947.7 1074.9 1248.5 1254.8 1270.9 1274.6 757.2 869.4 876.4 904.1 908.2 5461.2 5742.7 5872.5 5909.5 5981.1 866.2 890.2 894.3 903.8 920.1 3585.3 3837.7 3855.4 3856.6 3937.7 8945.5 9799.7 10075.0 10219.5 10366.9 9712.2 10317.9 10329.8 10330.2 10336.1 6773.8 7559.3 7982.8 8000.6 8006.4 5717.7 5800.2 5811.9 5986.4 6031.8 9378.3 9578.2 9600.9 9613.8 9625.6 9306.7 9623.5 9638.8 9863.5 9874.1 6133.9 6710.6 6933.5 6938.3 7405.4 7774.5 7881.0 8051.3 8130.3 8135.5 7013.1 7159.9 7165.5 7220.8 7298.5 7009.7 7054.5 7075.4 7201.0 7290.9 6588.0 6611.3 6620.8 6684.0 6711.5 4761.0 4785.0 4836.3 4836.6 4887.7 4752.9 4839.9 4889.2 4966.6 4975.8 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#4] prototype.html Cycles:180 Average:4336.99 Median:4810.52 stddev:1317.96 (27.4%) stddev-sans-first:1286.34 22:58:59 INFO - PROCESS | 2498 | Values: 298.7 301.2 307.6 311.5 312.8 5064.6 5271.9 5297.1 5319.5 5324.1 5020.4 5156.0 5160.6 5211.6 5255.4 4004.1 4030.8 4057.8 4062.6 4064.8 3743.9 3952.0 4003.3 4102.1 4143.5 3383.9 3945.8 3962.6 3968.1 3978.8 3949.7 3968.7 3974.0 3981.9 3986.8 3330.3 3416.7 3431.0 3489.6 3492.4 4581.1 4892.0 5038.9 5057.4 5129.5 4014.9 4305.2 4410.7 4499.6 4513.9 5368.6 5432.5 5460.3 5472.6 5500.3 3739.1 3758.4 3760.5 3793.8 3802.5 5133.1 5472.3 5719.7 5781.0 5826.7 3561.8 3764.1 3797.7 3827.2 3850.0 3853.9 3860.0 3863.3 3872.3 3894.8 4014.2 4101.0 4229.9 4482.8 4505.3 4596.9 5112.0 5414.9 5522.4 5559.9 1077.5 1141.8 1244.5 1247.3 1253.5 756.8 838.4 850.5 851.0 853.7 4798.9 4805.6 4809.7 4811.4 4835.7 4693.4 5245.5 5246.8 5290.7 5364.6 4235.4 4352.7 4799.5 4821.2 4844.9 2349.0 2505.8 2515.4 2742.8 2786.3 5243.6 5331.7 5503.3 5536.4 5663.9 5016.8 5413.9 5419.8 5422.5 5423.9 4841.7 5103.8 5242.0 5446.9 5483.2 4479.5 4536.9 5009.7 5248.5 5281.0 4173.5 4188.0 4349.4 4578.6 5240.9 4908.8 5116.8 5118.3 5418.3 5470.3 2988.5 3492.4 3591.7 3682.0 3776.2 5148.3 5587.6 5621.9 5624.0 5627.6 4244.5 5034.5 5457.2 5485.4 5589.4 4585.8 5154.6 5497.5 5536.8 5585.8 4753.0 5218.8 5414.9 5423.2 5448.0 4727.9 4895.8 5483.3 5528.8 5642.4 4549.0 4665.5 5374.7 5412.9 5438.9 22:58:59 INFO - PROCESS | 2498 | 22:58:59 INFO - PROCESS | 2498 | [#5] yui.html Cycles:180 Average:19268.76 Median:26092.72 stddev:27702.73 (106.2%) stddev-sans-first:27745.25 22:58:59 INFO - PROCESS | 2498 | Values: 673.9 705.7 718.8 749.1 770.3 14070.9 14835.5 14877.0 14878.6 14895.7 12841.9 12847.6 12857.1 12967.4 13017.5 638.1 659.1 661.7 790.2 800.3 801.9 806.7 830.0 830.0 830.4 875.9 877.4 879.4 879.9 883.0 624.9 641.4 642.1 870.7 874.2 488.7 488.9 527.0 640.4 641.2 14003.4 14021.6 14031.2 14041.2 14096.8 12813.9 13023.7 13158.0 13184.2 13265.3 11459.3 11761.7 11834.5 12107.3 12166.9 4275.4 4292.1 4388.3 4391.9 4453.5 69288.3 72531.3 72642.0 83873.3 86353.0 10416.4 10449.7 10582.7 10620.5 10900.0 18597.3 19581.8 20139.4 20291.8 20346.0 7733.3 7801.9 7837.2 7885.2 8004.2 4028.2 4151.9 4155.6 4189.9 4347.9 3440.8 3510.3 3531.6 3532.9 3563.6 639.2 655.6 663.9 668.1 668.5 579.0 590.4 607.1 613.5 634.0 8410.7 8737.2 8799.9 8889.2 9368.0 493.3 515.4 643.8 650.2 656.8 3362.5 4272.3 4321.4 4489.3 4512.5 68319.7 94128.0 98195.0 102645.5 103768.0 92290.1 93426.0 93729.5 94386.0 96036.5 71845.6 78806.2 79396.6 80759.6 84931.0 8508.1 8557.8 8617.7 8630.1 8795.6 52648.7 52977.0 53234.0 53306.5 53611.0 51658.5 76737.0 78391.6 79071.2 79527.2 10832.3 11033.0 11174.9 11305.9 11573.2 11965.2 12051.2 12100.6 12295.4 12419.8 11377.8 11884.6 11889.3 12040.6 12425.7 11818.3 11881.6 11927.8 12401.1 12416.0 11629.0 11675.9 11857.8 12293.6 12365.4 9358.0 9646.5 10188.2 10215.0 10275.6 9427.2 9949.6 9970.8 9991.4 10051.8 22:58:59 INFO - PROCESS | 2498 | -------- Summary: end -------- 22:58:59 INFO - PROCESS | 2498 | 22:59:00 INFO - TEST-INFO | 2498: exit 0 22:59:00 INFO - TEST-OK | dromaeo_css | took 1120293ms 22:59:00 INFO - TEST-START | kraken 22:59:00 INFO - operating with platform_type : mac_ 22:59:00 INFO - Initialising browser for kraken test... 22:59:00 INFO - TEST-INFO | started process 2501 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpARBHbs/profile http://localhost:63507/getInfo.html) 22:59:03 INFO - PROCESS | 2501 | __metrics Screen width/height:1600/1200 22:59:03 INFO - PROCESS | 2501 | colorDepth:24 22:59:03 INFO - PROCESS | 2501 | Browser inner width/height: 1024/689 22:59:03 INFO - PROCESS | 2501 | __metrics 22:59:03 INFO - PROCESS | 2501 | 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" 22:59:03 INFO - PROCESS | 2501 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:59:04 INFO - TEST-INFO | 2501: exit 0 22:59:04 INFO - Browser initialized. 22:59:04 INFO - Running cycle 1/1 for kraken test... 22:59:04 INFO - TEST-INFO | started process 2502 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpARBHbs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:16 INFO - PROCESS | 2502 | RSS: Main: 222973952 22:59:16 INFO - PROCESS | 2502 | 22:59:19 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63507/tests/kraken/driver.html?testName=audio-beat-detection) 22:59:20 INFO - PROCESS | 2502 | RSS: Main: 270319616 22:59:20 INFO - PROCESS | 2502 | 22:59:22 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63507/tests/kraken/driver.html?testName=audio-dft) 22:59:23 INFO - PROCESS | 2502 | RSS: Main: 284512256 22:59:23 INFO - PROCESS | 2502 | 22:59:26 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63507/tests/kraken/driver.html?testName=audio-fft) 22:59:27 INFO - PROCESS | 2502 | RSS: Main: 381243392 22:59:27 INFO - PROCESS | 2502 | 22:59:29 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63507/tests/kraken/driver.html?testName=audio-oscillator) 22:59:30 INFO - PROCESS | 2502 | RSS: Main: 283955200 22:59:30 INFO - PROCESS | 2502 | 22:59:32 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:59:32 INFO - PROCESS | 2502 | RSS: Main: 286441472 22:59:32 INFO - PROCESS | 2502 | 22:59:35 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63507/tests/kraken/driver.html?testName=imaging-darkroom) 22:59:36 INFO - PROCESS | 2502 | RSS: Main: 327393280 22:59:36 INFO - PROCESS | 2502 | 22:59:38 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63507/tests/kraken/driver.html?testName=imaging-desaturate) 22:59:39 INFO - PROCESS | 2502 | RSS: Main: 327938048 22:59:39 INFO - PROCESS | 2502 | 22:59:42 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63507/tests/kraken/driver.html?testName=json-parse-financial) 22:59:42 INFO - PROCESS | 2502 | RSS: Main: 322842624 22:59:42 INFO - PROCESS | 2502 | 22:59:44 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:59:45 INFO - PROCESS | 2502 | RSS: Main: 300032000 22:59:45 INFO - PROCESS | 2502 | 22:59:47 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:59:47 INFO - PROCESS | 2502 | RSS: Main: 301395968 22:59:47 INFO - PROCESS | 2502 | 22:59:49 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:59:50 INFO - PROCESS | 2502 | RSS: Main: 303001600 22:59:50 INFO - PROCESS | 2502 | 22:59:53 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:59:54 INFO - PROCESS | 2502 | RSS: Main: 312721408 22:59:54 INFO - PROCESS | 2502 | 22:59:57 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:59:57 INFO - PROCESS | 2502 | RSS: Main: 312373248 22:59:57 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:63507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63507/tests/kraken/driver.html?testName=ai-astar) 22:59:59 INFO - PROCESS | 2502 | RSS: Main: 356474880 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | __start_tp_report 22:59:59 INFO - PROCESS | 2502 | _x_x_mozilla_page_load 22:59:59 INFO - PROCESS | 2502 | _x_x_mozilla_page_load_details 22:59:59 INFO - PROCESS | 2502 | |i|pagename|runs| 22:59:59 INFO - PROCESS | 2502 | |0;ai-astar;104;113;115;113;113;115;113;113;113;113 22:59:59 INFO - PROCESS | 2502 | |1;audio-beat-detection;144;110;109;143;110;111;110;109;139;110 22:59:59 INFO - PROCESS | 2502 | |2;audio-dft;187;185;198;260;204;183;183;182;185;184 22:59:59 INFO - PROCESS | 2502 | |3;audio-fft;82;59;73;94;60;60;60;59;85;59 22:59:59 INFO - PROCESS | 2502 | |4;audio-oscillator;90;69;95;69;68;70;90;68;72;92 22:59:59 INFO - PROCESS | 2502 | |5;imaging-gaussian-blur;89;86;89;87;89;86;89;86;89;86 22:59:59 INFO - PROCESS | 2502 | |6;imaging-darkroom;97;98;98;98;97;98;98;101;97;96 22:59:59 INFO - PROCESS | 2502 | |7;imaging-desaturate;82;81;82;82;83;81;83;84;82;83 22:59:59 INFO - PROCESS | 2502 | |8;json-parse-financial;58;55;53;54;55;57;58;56;57;57 22:59:59 INFO - PROCESS | 2502 | |9;json-stringify-tinderbox;68;78;81;72;73;71;73;75;74;73 22:59:59 INFO - PROCESS | 2502 | |10;stanford-crypto-aes;85;84;82;84;85;84;84;84;87;84 22:59:59 INFO - PROCESS | 2502 | |11;stanford-crypto-ccm;143;140;147;157;139;147;144;158;213;153 22:59:59 INFO - PROCESS | 2502 | |12;stanford-crypto-pbkdf2;167;172;165;168;166;170;167;166;222;171 22:59:59 INFO - PROCESS | 2502 | |13;stanford-crypto-sha256-iterative;68;66;68;69;68;70;68;68;67;67 22:59:59 INFO - PROCESS | 2502 | __end_tp_report 22:59:59 INFO - PROCESS | 2502 | __start_cc_report 22:59:59 INFO - PROCESS | 2502 | _x_x_mozilla_cycle_collect,2016 22:59:59 INFO - PROCESS | 2502 | __end_cc_report 22:59:59 INFO - PROCESS | 2502 | __startTimestamp1483340399704__endTimestamp 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | ------- Summary: start ------- 22:59:59 INFO - PROCESS | 2502 | Number of tests: 14 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:3.10 (2.7%) stddev-sans-first:0.88 22:59:59 INFO - PROCESS | 2502 | Values: 104.0 113.0 115.0 113.0 113.0 115.0 113.0 113.0 113.0 113.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:110.50 stddev:15.59 (14.1%) stddev-sans-first:13.78 22:59:59 INFO - PROCESS | 2502 | Values: 144.0 110.0 109.0 143.0 110.0 111.0 110.0 109.0 139.0 110.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#2] audio-dft Cycles:10 Average:195.10 Median:186.00 stddev:23.94 (12.9%) stddev-sans-first:25.21 22:59:59 INFO - PROCESS | 2502 | Values: 187.0 185.0 198.0 260.0 204.0 183.0 183.0 182.0 185.0 184.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#3] audio-fft Cycles:10 Average:69.10 Median:66.50 stddev:13.37 (20.1%) stddev-sans-first:13.34 22:59:59 INFO - PROCESS | 2502 | Values: 82.0 59.0 73.0 94.0 60.0 60.0 60.0 59.0 85.0 59.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:81.00 stddev:11.71 (14.5%) stddev-sans-first:11.63 22:59:59 INFO - PROCESS | 2502 | Values: 90.0 69.0 95.0 69.0 68.0 70.0 90.0 68.0 72.0 92.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:89.00 stddev:1.51 (1.7%) stddev-sans-first:1.51 22:59:59 INFO - PROCESS | 2502 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 89.0 86.0 89.0 86.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:1.32 (1.3%) stddev-sans-first:1.36 22:59:59 INFO - PROCESS | 2502 | Values: 97.0 98.0 98.0 98.0 97.0 98.0 98.0 101.0 97.0 96.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:82.50 stddev:0.95 (1.1%) stddev-sans-first:1.00 22:59:59 INFO - PROCESS | 2502 | Values: 82.0 81.0 82.0 82.0 83.0 81.0 83.0 84.0 82.0 83.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.70 (3.0%) stddev-sans-first:1.64 22:59:59 INFO - PROCESS | 2502 | Values: 58.0 55.0 53.0 54.0 55.0 57.0 58.0 56.0 57.0 57.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:73.50 stddev:3.61 (4.9%) stddev-sans-first:3.17 22:59:59 INFO - PROCESS | 2502 | Values: 68.0 78.0 81.0 72.0 73.0 71.0 73.0 75.0 74.0 73.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 22:59:59 INFO - PROCESS | 2502 | Values: 85.0 84.0 82.0 84.0 85.0 84.0 84.0 84.0 87.0 84.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:150.00 stddev:21.72 (14.5%) stddev-sans-first:22.67 22:59:59 INFO - PROCESS | 2502 | Values: 143.0 140.0 147.0 157.0 139.0 147.0 144.0 158.0 213.0 153.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:169.00 stddev:17.23 (10.2%) stddev-sans-first:18.12 22:59:59 INFO - PROCESS | 2502 | Values: 167.0 172.0 165.0 168.0 166.0 170.0 167.0 166.0 222.0 171.0 22:59:59 INFO - PROCESS | 2502 | 22:59:59 INFO - PROCESS | 2502 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 22:59:59 INFO - PROCESS | 2502 | Values: 68.0 66.0 68.0 69.0 68.0 70.0 68.0 68.0 67.0 67.0 22:59:59 INFO - PROCESS | 2502 | -------- Summary: end -------- 22:59:59 INFO - PROCESS | 2502 | 23:00:00 INFO - TEST-INFO | 2502: exit 0 23:00:00 INFO - TEST-OK | kraken | took 60361ms 23:00:00 INFO - SUITE-END | took 1180s 23:00:00 INFO - Completed test suite (00:19:40) 23:00:00 INFO - javascript benchmark 23:00:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2613.6732908386452, 3294.8352582370876, 3307.61962374327, 3309.8179600121985, 3370.2080011197363, 5600.859978500534, 5680.9715951420285, 5693.914591281134, 5712.086066229404, 5735.168400581908, 5621.269234999455, 5703.77184912603, 5707.743151558179, 5719.313682358123, 5765.1352297155345, 5122.487751224876, 5173.094708426029, 5181.1969144782615, 5190.6107041972, 5214.791408343681, 5122.103631864427, 5144.022635699205, 5174.042802081627, 5315.0668505306185, 5326.2543243945875, 5267.76295066721, 5274.945010997816, 5289.206619007141, 5289.735513224324, 5295.894082118374, 5262.210668399752, 5277.525022747933, 5279.524842764151, 5281.102212623863, 5285.524302812765, 5112.46319136489, 5228.92156617651, 5232.58139348855, 5236.450172731885, 5268.972550352682, 5785.27684039495, 5789.855253618651, 5794.985877471426, 5799.536037117063, 5800.593958422912, 6104.76233045773, 6136.059583474755, 6138.830475372222, 6140.486742244447, 6148.38516148386, 6169.876602467971, 6230.283517395467, 6235.438810507031, 6242.625442473466, 6279.026750853625, 4814.879628009293, 5740.0, 5783.5951483396175, 5790.334111577138, 5802.245708057968, 6249.78125765596, 6309.3690630936535, 6326.114343991845, 6327.303996560375, 6332.430081292614, 6012.909806352908, 6025.4577088061405, 6084.117802918552, 6087.0, 6096.268447786205, 5682.488576028178, 5836.970815145897, 6273.1844860167885, 6287.088372186007, 6318.336574659686, 6187.876242475125, 6228.34602366754, 6236.126942228092, 6241.095041218998, 6242.063690446469, 5284.128118860345, 5908.936391449581, 6003.0, 6008.969955150196, 6042.909356359658, 4931.914978704679, 4945.986072855128, 4954.40547134339, 4957.3307603473795, 4984.825531106394, 4439.62263207631, 4446.844360447369, 4453.020335526179, 4464.129494748493, 4471.977640111843, 5591.41290164535, 5618.073017951992, 5675.914861277084, 5755.884882302331, 5775.27809023872, 6175.073738939105, 6202.100695399232, 6209.161763162006, 6218.968905155445, 6234.220722409699, 5785.929603023455, 5807.448292412214, 5810.82567522975, 5815.767369305265, 5829.912551311733, 5395.0, 5439.619226654097, 5450.291462109901, 5451.209574611738, 5463.672179669233, 6339.429451349309, 6441.13044738973, 6453.064305675557, 6458.031295305742, 6463.385978332145, 6136.23297087864, 6166.630002199882, 6170.290416602148, 6233.127362169209, 6277.2153480814895, 6362.395572420613, 6367.808965731036, 6411.487081033413, 6421.261554921244, 6431.099646049463, 6264.59280146789, 6272.4982001440685, 6272.560920735505, 6279.089532017923, 6280.7487700491465, 6035.215421995288, 6064.787732429344, 6113.29697084841, 6138.020573254662, 6282.8115156543545, 6038.63768173911, 6059.242594675666, 6138.817583427312, 6140.228185505611, 6148.78479253224, 5916.112583112567, 6044.969775151272, 6071.241094863142, 6090.086487026982, 6100.877982440237, 5752.597318187687, 5847.970760146342, 5916.55625828056, 5969.910451343147, 5971.671558064174, 5952.434518720715, 6005.489533389536, 6024.849378765566, 6025.517958563217, 6029.0, 5870.618409803349, 5876.706164691663, 5887.52899768009, 5889.941100589111, 5893.174955506147], "value": 5680.838233764297, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.686445971268, 509.23360342684254, 509.9184130539114, 515.3635260453342, 537.6290359651838, 27689.0, 29258.268543286402, 39399.80300098495, 40682.79658601703, 40886.59113408865, 42099.57900420995, 42535.14929701412, 42567.57432425675, 42628.3605745914, 42629.78685106578, 9335.533223338805, 9353.579088941015, 9498.952505237465, 9544.713658590219, 9587.23302135831, 8498.065212826585, 8759.649614015432, 11080.0, 11419.54321827126, 11428.314301141958, 11284.379359135244, 11328.716782080473, 11336.979671829533, 11339.433028348549, 11379.317240965527, 8505.0, 11379.487923043442, 11444.027257683148, 11457.484413201426, 11465.31208127515, 8551.059383467813, 10985.0, 11363.829542556869, 11388.14588905827, 11505.194636375458, 32219.51670724941, 39866.0, 40116.398254026215, 40143.0, 40149.99625009369, 3226.6128064632157, 3284.1132894118455, 3462.2556150427527, 3488.778927375424, 3822.9808850955847, 28262.717372826213, 28336.0, 29397.85301073481, 29737.70262297371, 29973.950911717984, 3093.2266933266683, 3095.133362658459, 3322.435186018383, 3322.9169270768184, 3324.1855745342305, 356002.0, 357515.9999999974, 359742.0, 359953.0, 360685.0, 58231.4176858226, 58747.41252587505, 58816.41183588195, 58818.41181588215, 58869.0, 62882.68558657269, 63075.0, 63091.0, 63106.368936310966, 63170.36829631737, 15806.60483487922, 15995.760063599055, 16032.198390080683, 16033.599160021093, 16049.438269660739, 11167.776644467227, 11439.256448330832, 12411.0, 12425.689357765947, 12461.127721059438, 14788.408463661548, 14891.404343826342, 14894.404223831141, 14900.329485173193, 14922.40310387594, 6324.525660575475, 6856.828579285558, 8001.679932802739, 8062.355011599058, 8111.229433203836, 813.7762115418308, 933.09955892564, 984.6750572311263, 1026.2816028779885, 1030.299396410448, 28976.565351519745, 29936.700632993827, 29990.95031673881, 30462.933797316986, 30480.54279185814, 984.0503913723292, 1031.9380837149795, 1034.4879284754095, 1048.491481631408, 1066.4507778494083, 15108.39566417331, 15376.692466150615, 15446.691066178839, 15466.5360039199, 15808.52574422769, 706291.0, 712247.0, 712677.0, 713513.0, 713669.0, 57333.71333143308, 57750.0, 57767.711161444764, 57770.0, 58012.41987580155, 1206.4570943075475, 1257.5032862019634, 1258.282778816066, 1348.6426097084282, 1379.5033787836371, 24186.51626967486, 26076.0, 26132.8693356532, 31149.0, 31154.0, 55013.0, 55355.72322138525, 55765.72117139388, 55777.44222557722, 55813.0, 638591.0, 642279.0, 643969.9007524662, 644160.0, 644668.0, 26712.0, 27117.186484405498, 27690.723092768814, 42280.154396911275, 42952.57047429611, 43126.78436607922, 45555.088898222515, 46115.3082703753, 46534.30198547092, 46803.063938720356, 36930.815345923096, 37092.44361334636, 37111.628883710815, 37290.0, 38144.80927595344, 36784.26431471409, 36922.26155476929, 37458.0, 37968.24063518769, 37997.81001094977, 37116.072098197765, 37821.0, 37856.053598660255, 37957.0, 38154.236915262096, 31116.844415777778, 32859.178520536225, 33130.17174570655, 33213.0, 33374.833125834215, 24928.626070608592, 25119.74880251174, 25253.0, 25325.493490130462, 25909.22272331833], "value": 19894.321072322513, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9236767911059, 346.9739769517285, 359.36213221531784, 369.51777929801597, 374.41778035155323, 14560.635984100403, 14787.186704731235, 15689.686206275883, 16358.591035224126, 20422.489437764063, 19037.809621903776, 19567.510812229702, 20036.39890803278, 20320.89839550804, 20349.0, 5748.5688573357065, 6061.12113743507, 6069.939300607004, 6079.452849243589, 6085.178500902388, 5649.49154576088, 5922.822315330547, 5962.986292330314, 5973.88052238955, 6006.669633170174, 5928.3775203603645, 5929.229200203989, 5936.970315148418, 5942.702864856761, 5947.88104237915, 5640.689762063085, 5860.765569377241, 5926.318473375574, 5928.49607783339, 5932.911006334908, 4944.258361245842, 5180.119379705459, 5184.844454666366, 5185.870353241162, 5187.870303242411, 24512.387190320205, 24779.0, 24787.25638230856, 24825.50348993028, 25134.371640708945, 2412.1075202175307, 2456.238566044514, 2497.500499900027, 2532.2909585316143, 2886.3794284228993, 15007.474738384104, 15051.924740376338, 15449.845501545065, 15836.683266334609, 16058.518244452685, 1747.3360123153248, 1817.5183576352276, 1819.1540933466001, 1869.7382366468705, 1881.3791448821855, 117366.82633173729, 117934.0, 120969.39515302367, 121899.0, 123738.38130809378, 8797.604107815036, 10512.57949682004, 10921.39932303715, 11186.38474883873, 11462.0, 13539.187648741106, 13601.455941762219, 13616.86383136156, 13617.523386681418, 13673.726525469436, 8035.316998055231, 8241.464304820167, 8274.29668478174, 8355.289800366916, 8446.788830279293, 8283.08886022537, 8295.050663094167, 8328.875066874001, 8338.124496927856, 8497.490150590984, 5412.945870541244, 6463.7091330890225, 6542.869142617121, 6555.475561955032, 6703.262641109474, 1516.6132636177704, 1750.361118191851, 1792.4353828543967, 1792.507060558357, 1795.5511122219445, 876.2289185516831, 935.9532023398802, 963.7686955130719, 1005.3666190300064, 1027.2398425165325, 21478.677819832716, 22082.668759968612, 22219.888900555394, 22268.66597001046, 22296.442588935406, 858.6350800909588, 883.9558022098869, 918.8989211186764, 977.1742877268697, 1016.4511163971521, 11076.612318568972, 11557.190996630155, 11645.301281923112, 11646.184767066394, 11682.76634467323, 601896.0, 616070.0, 617818.0, 629401.0, 632532.0, 104669.0, 112105.87894121118, 112465.0, 114649.0, 121873.39063304626, 811.1563973467646, 992.5880759484733, 1004.1866088468219, 1005.8692369991854, 1008.2438171371472, 14895.584919432637, 18894.96077715739, 19371.903140484206, 19969.700454493486, 20098.995050247137, 51889.0, 53465.18813306821, 53935.46064539305, 54548.0, 55457.1681424787, 69454.65272673806, 70662.64668676624, 73303.63348183225, 75221.0, 75965.0, 8072.112067672434, 8119.634616442392, 8298.170182981654, 8348.833023339377, 8412.844672987874, 10126.240531959986, 12477.688057798629, 12529.874701252871, 12913.289769062416, 12935.547255846377, 10356.585736570452, 10622.256442048982, 10804.783904321712, 10967.067799237155, 11128.666140015812, 10761.784764304826, 10906.945465272624, 10940.890591093987, 11289.830652540055, 11299.548018079186, 10877.238593298393, 11064.889351106385, 11138.888611113785, 11235.943820280847, 11446.0, 8100.35197184212, 8152.429329946846, 8152.877706834283, 8303.958480207802, 8344.332453403835, 9227.492487913229, 11001.941108078252, 11244.66266012021, 11269.943650282024, 11295.943520282675], "value": 9604.558266104359, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.949251776188, 1460.8247010358755, 1469.8162729658793, 1780.866435017374, 1788.6601545706324, 8044.6379912903985, 8497.447665901713, 8520.786980325494, 8520.892284003086, 8521.616527256272, 7422.035135432387, 7696.653650585723, 7712.96143519283, 8037.156098609649, 8321.833563328744, 2308.9538209235807, 2349.694539709835, 2350.788429041386, 2357.49313897512, 2357.610994185966, 2345.8006069484113, 2371.1819422299277, 2391.35433432973, 2399.592069348215, 2402.0, 2318.652202169671, 2328.1036800831716, 2346.5893468643003, 2351.7413084560685, 2357.387079359362, 2292.770722927694, 2293.5298263855952, 2316.339843144696, 2317.1194945920442, 2323.4423738302853, 2336.380859072347, 2362.4684445999683, 2364.0898254172093, 2370.9804783942895, 2371.905123795046, 8242.340612750966, 8409.453385529921, 8415.03227128882, 8441.662333506652, 8443.0, 5063.341765570489, 5229.895402091937, 5229.895402091975, 5248.81629142982, 5255.6583822051825, 2742.616033755276, 3368.1411240133607, 3593.964060359389, 3602.495650608917, 3607.134287770942, 1033.1889466768625, 1037.1391744851755, 1059.8622179116742, 1097.8078836203633, 1117.5473933057044, 10943.0, 10991.175661825315, 11151.832722509169, 11159.609413670563, 11176.832347514794, 4540.72755634663, 4607.631389488833, 4738.905221895612, 4749.430068391815, 4793.257045158006, 9542.379745316532, 9555.713328600154, 9563.856542151734, 9574.808503829885, 9586.760330991781, 5245.868853278622, 5248.475152484721, 5249.7112658804135, 5250.894982100336, 5263.5525980292105, 4036.616521430459, 4120.25835349634, 4787.880302992384, 4799.424069111729, 4802.8799280018275, 3816.904577385588, 3830.5594856591847, 3898.161895192547, 3934.3311637021775, 3947.703922205789, 1074.9140068794477, 1248.5005997601031, 1254.8494180698374, 1270.9364531773374, 1274.6048724895313, 757.2049348184495, 869.4348673362239, 876.4040452492239, 904.0868722590232, 908.1599520443564, 5461.208124821878, 5742.684152371659, 5872.530197584183, 5909.527237820964, 5981.132735753292, 866.2117473099449, 890.1632465482555, 894.2979760887672, 903.8418276801533, 920.0798260861022, 3585.30086633104, 3837.6737977271687, 3855.4024126260474, 3856.633619806114, 3937.7243592948794, 8945.507997060095, 9799.657012004545, 10075.0, 10219.48902554884, 10366.948165259126, 9712.22302215821, 10317.896821031694, 10329.793404131875, 10330.173586113093, 10336.0697537222, 6773.830654233683, 7559.3196612304055, 7982.840343193104, 8000.63997120131, 8006.3995200360205, 5717.656940583578, 5800.216970708901, 5811.94188058114, 5986.371430999682, 6031.819045428644, 9378.296627753083, 9578.185854202471, 9600.85598716006, 9613.759656008655, 9625.614975401186, 9306.72079837606, 9623.518824058629, 9638.807223855623, 9863.457509837028, 9874.061964113398, 6133.938660613515, 6710.597364158166, 6933.5493192942295, 6938.271481494572, 7405.370543503863, 7774.494657847222, 7880.960595196987, 8051.315638170821, 8130.349572034342, 8135.471194372347, 7013.123359580052, 7159.892601610876, 7165.534240274365, 7220.783376498714, 7298.4891057625455, 7009.719611215494, 7054.540013466629, 7075.363217310467, 7200.9639951802, 7290.927090729025, 6587.9670601646685, 6611.2727599963, 6620.8344791380605, 6684.030815531623, 6711.530192886451, 4760.97619511914, 4784.95215047859, 4836.322914791862, 4836.564709176191, 4887.682300650446, 4752.857414277577, 4839.903201936012, 4889.193283108248, 4966.553010229097, 4975.776090076028], "value": 4442.86334932303, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.67146139246825, 301.1988111623082, 307.5555821837445, 311.4953774884687, 312.7654259305521, 5064.56951159152, 5271.894562108756, 5297.07301222286, 5319.547838433733, 5324.056719297458, 5020.3724534433195, 5156.020356132332, 5160.612954028454, 5211.609129315298, 5255.372313843079, 4004.1190937993597, 4030.7984600769846, 4057.756534607933, 4062.5531191568903, 4064.7602481243207, 3743.86896458624, 3952.0, 4003.279409706266, 4102.087517862321, 4143.544210136897, 3383.9154021149543, 3945.842166313344, 3962.603739626043, 3968.1270120573427, 3978.781167035812, 3949.6840252779707, 3968.7179871573962, 3973.9534139917964, 3981.880543583697, 3986.7807270600106, 3330.333933213343, 3416.7437442191936, 3430.9656903430896, 3489.557309883092, 3492.388831954398, 4581.060882519076, 4891.972685736, 5038.949610503922, 5057.418396884332, 5129.461406552333, 4014.879553613396, 4305.203537345601, 4410.691251612388, 4499.550044995507, 4513.909721805579, 5368.597355198376, 5432.538234250093, 5460.34475862895, 5472.616916815825, 5500.339959204881, 3739.121306492972, 3758.3798673218885, 3760.5487341519192, 3793.823914586487, 3802.5056742623283, 5133.0503856786245, 5472.288602481652, 5719.656820590736, 5781.0, 5826.650400975955, 3561.7684850484634, 3764.134249122717, 3797.6582107610448, 3827.234553089393, 3849.9807500962315, 3853.9421908671393, 3860.0, 3863.3194560366333, 3872.341701910682, 3894.805259737002, 4014.1770936957855, 4101.0, 4229.936550951738, 4482.8206871725415, 4505.346724724896, 4596.862094137181, 5111.95521791279, 5414.945850541523, 5522.3649280333275, 5559.861003474945, 1077.5205033760058, 1141.7887690777234, 1244.539520377466, 1247.2765795838393, 1253.486070711004, 756.8070142113836, 838.4466252273592, 850.4939560961218, 850.9914900851036, 853.7396094191331, 4798.936457323314, 4805.639577031667, 4809.687370320988, 4811.350467686888, 4835.734034628059, 4693.389859318335, 5245.527902488794, 5246.816361427332, 5290.735463226823, 5364.597655175799, 4235.449391579075, 4352.69531132824, 4799.544043315879, 4821.156297647897, 4844.927326090112, 2349.036894873094, 2505.837120587156, 2515.421453065805, 2742.835429874214, 2786.275568352243, 5243.580513558983, 5331.733413329318, 5503.284573005563, 5536.363318218457, 5663.858403539862, 5016.849494515171, 5413.891722165535, 5419.756110975016, 5422.457754224546, 5423.891522169534, 4841.685290456179, 5103.795848166032, 5241.9516096780035, 5446.863828404243, 5483.2323474712775, 4479.46246450428, 4536.9092618148115, 5009.749512524432, 5248.501392367872, 5281.042616134977, 4173.540910499782, 4187.958120418757, 4349.412829268008, 4578.5650363215445, 5240.947590524199, 4908.7545622719435, 5116.780883498885, 5118.283440318284, 5418.315545429146, 5470.288862448016, 2988.462076826192, 3492.4062909305985, 3591.6587924147166, 3682.0359610512724, 3776.206996530759, 5148.330717006766, 5587.552995760248, 5621.943780562306, 5623.959567480029, 5627.606067575231, 4244.469441319835, 5034.546890779848, 5457.20870473787, 5485.3543936818, 5589.385167631476, 4585.770711464346, 5154.634021984252, 5497.471163264783, 5536.785285885678, 5585.832425027255, 4752.952470475389, 5218.817341393033, 5414.8646283841645, 5423.186522021729, 5448.0, 4727.858164254941, 4895.779689914034, 5483.297370734392, 5528.834134975797, 5642.379338272869, 4548.998440686088, 4665.463471700662, 5374.731263436891, 5412.945870541244, 5438.891222175455], "value": 3913.5233549710074, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.9224989126251, 705.7282946065764, 718.809515478398, 749.0861149397737, 770.2720928722356, 14070.859291407083, 14835.480758173466, 14877.0, 14878.628034299149, 14895.702085958274, 12841.935790321058, 12847.550335738251, 12857.100002999794, 12967.416466259017, 13017.544385946494, 638.097092613953, 659.1134923527848, 661.6592454885738, 790.2255789326464, 800.275750445846, 801.8917446144783, 806.6531391501652, 829.9832704936434, 830.0081402723747, 830.3523251863556, 875.9124087591254, 877.4472082587961, 879.4371602174614, 879.9472031678088, 883.0374891368407, 624.9343818898996, 641.4066988036049, 642.097852517214, 870.708312715238, 874.2001069021837, 488.6847983050948, 488.89488759916435, 526.9525742683177, 640.4300172846124, 641.2433328672165, 14003.439862405594, 14021.64945876351, 14031.228282444461, 14041.157530548098, 14096.788548171786, 12813.87186128136, 13023.674408139777, 13158.013149013863, 13184.208947463087, 13265.27041012744, 11459.255148415326, 11761.705957351049, 11834.526618935231, 12107.334096624681, 12166.878331216663, 4275.444192255018, 4292.098659281554, 4388.341748737683, 4391.890202744957, 4453.487848897354, 69288.30711692868, 72531.27468725298, 72642.0, 83873.3225335496, 86353.0, 10416.427096509613, 10449.738756531147, 10582.682519524427, 10620.468976551141, 10900.0, 18597.34909278169, 19581.804181958283, 20139.39581812548, 20291.797082028992, 20345.982700865192, 7733.265339792823, 7801.882971755428, 7837.216278372117, 7885.1720569339395, 8004.159563245776, 4028.1742242840146, 4151.8546850860075, 4155.605217504332, 4189.874303770892, 4347.869563913087, 3440.776349537272, 3510.315488479722, 3531.611522732497, 3532.8586856525453, 3563.6080031196548, 639.229728177545, 655.6099121022984, 663.8705452436728, 668.1180841289452, 668.4785867023729, 578.9913151302734, 590.35356284868, 607.1318015238252, 613.5091926458815, 633.9746410143634, 8410.705625303084, 8737.21365077146, 8799.91200087991, 8889.244414224733, 9367.953160234156, 493.3019777015483, 515.3712470785636, 643.829385212919, 650.200253687957, 656.8373978058809, 3362.4956256561713, 4272.252355837716, 4321.416608757841, 4489.349044388546, 4512.503624601291, 68319.65840170767, 94128.0, 98195.0, 102645.46031809528, 103767.96232037734, 92290.07709922949, 93426.0, 93729.5313523428, 94386.0, 96036.51981740046, 71845.6407717958, 78806.21193788103, 79396.60301698338, 80759.59620201861, 84931.0, 8508.149185081442, 8557.828843422973, 8617.655293788179, 8630.136986301319, 8795.604197811243, 52648.68378290573, 52977.0, 53234.0, 53306.46693533015, 53611.0, 51658.48341516537, 76737.0, 78391.60804195942, 79071.20928790639, 79527.20472795429, 10832.295900766425, 11033.0, 11174.88825111771, 11305.886941130484, 11573.247738896946, 11965.162438629213, 12051.156419050581, 12100.555597080142, 12295.44670489848, 12419.813702794285, 11377.772444551229, 11884.58403955892, 11889.34608596501, 12040.638780836589, 12425.689357766129, 11818.321724841757, 11881.5841445549, 11927.76144477123, 12401.13192076582, 12416.006719462603, 11629.0, 11675.88324116782, 11857.822132667845, 12293.631191064282, 12365.381730913597, 9357.953210233905, 9646.469444180637, 10188.184945204513, 10215.02957219063, 10275.588976440858, 9427.245820334494, 9949.552270148006, 9970.800583988135, 9991.447551613055, 10051.849222261526], "value": 6980.273162880617, "unit": "score"}], "name": "dromaeo_css", "value": 7133.377859554332, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 115.0, 113.0, 113.0, 115.0, 113.0, 113.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 109.0, 143.0, 110.0, 111.0, 110.0, 109.0, 139.0, 110.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 198.0, 260.0, 204.0, 183.0, 183.0, 182.0, 185.0, 184.0], "value": 195.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 73.0, 94.0, 60.0, 60.0, 60.0, 59.0, 85.0, 59.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 95.0, 69.0, 68.0, 70.0, 90.0, 68.0, 72.0, 92.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 89.0, 86.0, 89.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 98.0, 97.0, 98.0, 98.0, 101.0, 97.0, 96.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 82.0, 83.0, 81.0, 83.0, 84.0, 82.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 53.0, 54.0, 55.0, 57.0, 58.0, 56.0, 57.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 81.0, 72.0, 73.0, 71.0, 73.0, 75.0, 74.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 82.0, 84.0, 85.0, 84.0, 84.0, 84.0, 87.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 147.0, 157.0, 139.0, 147.0, 144.0, 158.0, 213.0, 153.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 165.0, 168.0, 166.0, 170.0, 167.0, 166.0, 222.0, 171.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 69.0, 68.0, 70.0, 68.0, 68.0, 67.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1451.7, "alertThreshold": 2.0}]} 23:00:00 INFO - Return code: 0 23:00:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:00:01 INFO - # TBPL SUCCESS # 23:00:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:00:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:00:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:00:01 INFO - 2017-01-01 23:00:01.030 screenresolution[2506:87678] starting screenresolution argv=screenresolution get 23:00:01 INFO - 2017-01-01 23:00:01.032 screenresolution[2506:87678] Display 0: 1600x1200x32@60 23:00:01 INFO - 2017-01-01 23:00:01.039 screenresolution[2507:87681] starting screenresolution argv=screenresolution list 23:00:01 INFO - Available Modes on Display 0 23:00:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:00:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:00:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:00:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:00:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:00:01 INFO - Intel Iris: 23:00:01 INFO - Chipset Model: Intel Iris 23:00:01 INFO - Type: GPU 23:00:01 INFO - Bus: Built-In 23:00:01 INFO - VRAM (Dynamic, Max): 1536 MB 23:00:01 INFO - Vendor: Intel (0x8086) 23:00:01 INFO - Device ID: 0x0a2e 23:00:01 INFO - Revision ID: 0x0009 23:00:01 INFO - Displays: 23:00:01 INFO - Display: 23:00:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:00:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:00:01 INFO - Display Serial Number: 1600x1200 60 23:00:01 INFO - Main Display: Yes 23:00:01 INFO - Mirror: Off 23:00:01 INFO - Online: Yes 23:00:01 INFO - Rotation: Supported 23:00:01 INFO - Return code: 0 23:00:01 INFO - Running post-action listener: _resource_record_post_action 23:00:01 INFO - [mozharness: 2017-01-02 07:00:01.291754Z] Finished run-tests step (success) 23:00:01 INFO - Running post-run listener: _resource_record_post_run 23:00:01 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 53248; Write bytes: 655821824; Read time: 2; Write time: 1913 23:00:01 INFO - TinderboxPrint: CPU usage
53.4% 23:00:01 INFO - TinderboxPrint: I/O read bytes / time
53,248 / 2 23:00:01 INFO - TinderboxPrint: I/O write bytes / time
655,821,824 / 1,913 23:00:01 INFO - TinderboxPrint: CPU idle
2,259.9 (46.6%) 23:00:01 INFO - TinderboxPrint: CPU system
969.6 (20.0%) 23:00:01 INFO - TinderboxPrint: CPU user
1,620.2 (33.4%) 23:00:01 INFO - TinderboxPrint: Swap in / out
348,393,472 / 0 23:00:01 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 344007168; Write bytes: 346300928; Read time: 26759; Write time: 727 23:00:01 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 53248; Write bytes: 302549504; Read time: 2; Write time: 1155 23:00:01 INFO - Running post-run listener: _upload_blobber_files 23:00:01 INFO - Blob upload gear active. 23:00:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:00:01 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 23:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:00:02 INFO - (blobuploader) - INFO - Open directory for files ... 23:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:00:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:03 INFO - (blobuploader) - INFO - Done attempting. 23:00:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:00:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:00:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:06 INFO - (blobuploader) - INFO - Done attempting. 23:00:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:00:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:00:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:08 INFO - (blobuploader) - INFO - Done attempting. 23:00:08 INFO - (blobuploader) - INFO - Iteration through files over. 23:00:08 INFO - Return code: 0 23:00:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:00:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:00:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/327e9cde18c98d2ba4b8f7804389dc081d0653c301e8b8a721f816413eb4c6a3d5f4c2a6e5c174a82716f7e9e3ea7f63918ea9bfa9c7b02f359c030cecdd7a1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17762e2bf3a0a7410902ab0e762899a9ea0fdddfc6115aace8a3d641206833f8a987fd05f9e877ce55199e5fdd502f2185ed6bff8f44b5615b77dee8e0bc2b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e00dbd44ffb1b8a4b5819fd8f4a2e0c2c0409146b9883811127cc0101dbcc44a5316bdf0b36231296f453d77e08ea5eb648f29f5c41be15741cd7fab6b3616b"} 23:00:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:00:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:00:08 INFO - Contents: 23:00:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/327e9cde18c98d2ba4b8f7804389dc081d0653c301e8b8a721f816413eb4c6a3d5f4c2a6e5c174a82716f7e9e3ea7f63918ea9bfa9c7b02f359c030cecdd7a1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17762e2bf3a0a7410902ab0e762899a9ea0fdddfc6115aace8a3d641206833f8a987fd05f9e877ce55199e5fdd502f2185ed6bff8f44b5615b77dee8e0bc2b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e00dbd44ffb1b8a4b5819fd8f4a2e0c2c0409146b9883811127cc0101dbcc44a5316bdf0b36231296f453d77e08ea5eb648f29f5c41be15741cd7fab6b3616b"} 23:00:08 INFO - Running post-run listener: copy_logs_to_upload_dir 23:00:08 INFO - Copying logs to upload dir... 23:00:08 INFO - mkdir: /builds/slave/test/build/upload/logs 23:00:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.182574 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-01 23:00:08.447302) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 23:00:08.450798) ========= 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.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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/Mozilla-Aurora/sha512/327e9cde18c98d2ba4b8f7804389dc081d0653c301e8b8a721f816413eb4c6a3d5f4c2a6e5c174a82716f7e9e3ea7f63918ea9bfa9c7b02f359c030cecdd7a1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17762e2bf3a0a7410902ab0e762899a9ea0fdddfc6115aace8a3d641206833f8a987fd05f9e877ce55199e5fdd502f2185ed6bff8f44b5615b77dee8e0bc2b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e00dbd44ffb1b8a4b5819fd8f4a2e0c2c0409146b9883811127cc0101dbcc44a5316bdf0b36231296f453d77e08ea5eb648f29f5c41be15741cd7fab6b3616b"} build_url:https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/DAhO-fa9T-eto3Bqbp5T6w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/327e9cde18c98d2ba4b8f7804389dc081d0653c301e8b8a721f816413eb4c6a3d5f4c2a6e5c174a82716f7e9e3ea7f63918ea9bfa9c7b02f359c030cecdd7a1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17762e2bf3a0a7410902ab0e762899a9ea0fdddfc6115aace8a3d641206833f8a987fd05f9e877ce55199e5fdd502f2185ed6bff8f44b5615b77dee8e0bc2b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e00dbd44ffb1b8a4b5819fd8f4a2e0c2c0409146b9883811127cc0101dbcc44a5316bdf0b36231296f453d77e08ea5eb648f29f5c41be15741cd7fab6b3616b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 23:00:08.485307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 23:00:08.485648) ========= 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.d0RaYfRT53/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.Ws24tExRs9/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.005348 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 23:00:08.534046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 23:00:08.534366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 23:00:08.534675) ========= ========= Total master_lag: 0.18 =========