builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0362 starttime: 1478623019.06 results: success (0) buildid: 20161108062935 builduid: ca82d5bbd00a4f2186b6aa2f2238e06a revision: aea5b4c3d165dcde027b3b6551b146a56748e9be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.058842) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.059382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.059693) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.089092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.089477) ========= 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.qeeHlKZZ9r/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.jYdWaH3f82/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 --2016-11-08 08:36:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2016-11-08 08:36:59 (200 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125100 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.238768) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.239285) ========= 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.qeeHlKZZ9r/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.jYdWaH3f82/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.024883 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:59.288677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-08 08:36:59.289042) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aea5b4c3d165dcde027b3b6551b146a56748e9be --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev aea5b4c3d165dcde027b3b6551b146a56748e9be --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qeeHlKZZ9r/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.jYdWaH3f82/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 2016-11-08 08:36:59,369 truncating revision to first 12 chars 2016-11-08 08:36:59,369 Setting DEBUG logging. 2016-11-08 08:36:59,369 attempt 1/10 2016-11-08 08:36:59,369 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aea5b4c3d165?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 08:37:01,431 unpacking tar archive at: mozilla-central-aea5b4c3d165/testing/mozharness/ program finished with exit code 0 elapsedTime=2.323970 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-08 08:37:01.635846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 08:37:01.636223) ========= script_repo_revision: aea5b4c3d165dcde027b3b6551b146a56748e9be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 08:37:01.636825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 08:37:01.637160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 08:37:01.656007) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-08 08:37:01.656401) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:01 INFO - MultiFileLogger online at 20161108 08:37:01 in /builds/slave/test 08:37:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:37:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:37:01 INFO - {'append_to_log': False, 08:37:01 INFO - 'base_work_dir': '/builds/slave/test', 08:37:01 INFO - 'blob_upload_branch': 'Firefox', 08:37:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:37:01 INFO - 'branch': 'Firefox', 08:37:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:37:01 INFO - 'config_files': ('talos/mac_config.py',), 08:37:01 INFO - 'default_actions': ('clobber', 08:37:01 INFO - 'read-buildbot-config', 08:37:01 INFO - 'download-and-extract', 08:37:01 INFO - 'populate-webroot', 08:37:01 INFO - 'create-virtualenv', 08:37:01 INFO - 'install', 08:37:01 INFO - 'run-tests'), 08:37:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:37:01 INFO - 'download_minidump_stackwalk': True, 08:37:01 INFO - 'download_symbols': 'ondemand', 08:37:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:37:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:37:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:37:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:37:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:37:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:37:01 INFO - 'installer_path': 'installer.exe', 08:37:01 INFO - 'log_level': 'info', 08:37:01 INFO - 'log_name': 'talos', 08:37:01 INFO - 'log_to_console': True, 08:37:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:37:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:37:01 INFO - 'opt_config_files': (), 08:37:01 INFO - 'pip_index': False, 08:37:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:37:01 INFO - 'cmd': ('bash', 08:37:01 INFO - '-c', 08:37:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:37:01 INFO - 'enabled': True, 08:37:01 INFO - 'halt_on_failure': False, 08:37:01 INFO - 'name': 'check_screen_resolution'},), 08:37:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:37:01 INFO - 'cmd': ('bash', 08:37:01 INFO - '-c', 08:37:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:37:01 INFO - 'enabled': True, 08:37:01 INFO - 'halt_on_failure': False, 08:37:01 INFO - 'name': 'check_screen_resolution'},), 08:37:01 INFO - 'run_cmd_checks_enabled': True, 08:37:01 INFO - 'sps_profile': False, 08:37:01 INFO - 'sps_profile_interval': 0, 08:37:01 INFO - 'suite': 'dromaeojs-e10s', 08:37:01 INFO - 'system_bits': '32', 08:37:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:37:01 INFO - 'title': 't-yosemite-r7-0362', 08:37:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:37:01 INFO - 'use_talos_json': True, 08:37:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:37:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:37:01 INFO - 'work_dir': 'build'} 08:37:01 INFO - [mozharness: 2016-11-08 16:37:01.859387Z] Running clobber step. 08:37:01 INFO - Running pre-action listener: _resource_record_pre_action 08:37:01 INFO - Running main action method: clobber 08:37:01 INFO - rmtree: /builds/slave/test/build 08:37:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:37:04 INFO - Running post-action listener: _resource_record_post_action 08:37:04 INFO - [mozharness: 2016-11-08 16:37:04.528077Z] Finished clobber step (success) 08:37:04 INFO - [mozharness: 2016-11-08 16:37:04.528237Z] Running read-buildbot-config step. 08:37:04 INFO - Running pre-action listener: _resource_record_pre_action 08:37:04 INFO - Running main action method: read_buildbot_config 08:37:04 INFO - Using buildbot properties: 08:37:04 INFO - { 08:37:04 INFO - "project": "", 08:37:04 INFO - "product": "firefox", 08:37:04 INFO - "script_repo_revision": "production", 08:37:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:37:04 INFO - "repository": "", 08:37:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:37:04 INFO - "buildid": "20161108062935", 08:37:04 INFO - "pgo_build": "False", 08:37:04 INFO - "basedir": "/builds/slave/test", 08:37:04 INFO - "buildnumber": 22, 08:37:04 INFO - "slavename": "t-yosemite-r7-0362", 08:37:04 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:37:04 INFO - "platform": "yosemite_r7", 08:37:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:37:04 INFO - "branch": "mozilla-central", 08:37:04 INFO - "repo_path": "mozilla-central", 08:37:04 INFO - "revision": "aea5b4c3d165dcde027b3b6551b146a56748e9be", 08:37:04 INFO - "stage_platform": "macosx64", 08:37:04 INFO - "builduid": "ca82d5bbd00a4f2186b6aa2f2238e06a", 08:37:04 INFO - "slavebuilddir": "test" 08:37:04 INFO - } 08:37:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:37:04 INFO - Running post-action listener: _resource_record_post_action 08:37:04 INFO - [mozharness: 2016-11-08 16:37:04.531647Z] Finished read-buildbot-config step (success) 08:37:04 INFO - [mozharness: 2016-11-08 16:37:04.531778Z] Running download-and-extract step. 08:37:04 INFO - Running pre-action listener: _resource_record_pre_action 08:37:04 INFO - Running main action method: download_and_extract 08:37:04 INFO - mkdir: /builds/slave/test/build/tests 08:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:04 INFO - trying https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:37:04 INFO - Downloading https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:37:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:37:05 INFO - Downloaded 1199 bytes. 08:37:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:37:05 INFO - Using the following test package requirements: 08:37:05 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:37:05 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:37:05 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:37:05 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'jsshell-mac.zip'], 08:37:05 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:37:05 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:37:05 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:37:05 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:37:05 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:37:05 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:37:05 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:37:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:37:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:37:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:37:05 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:37:06 INFO - Http code: 200 08:37:06 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:37:06 INFO - x-amz-cf-id: 675Msfqsi32ewTrBWsqGV-ZmgBlAifTOusOxbTXcvhLXBBpAlG9DxQ== 08:37:06 INFO - x-amz-version-id: rINV6su_sxI2zOZ2S1G4JiZUPESq27Mz 08:37:06 INFO - Expected file size: 16310108 08:37:06 INFO - Obtained file size: 16310108 08:37:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:37:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:37:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:37:13 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:37:14 INFO - Http code: 200 08:37:14 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 08:37:14 INFO - x-amz-cf-id: WUEeltTskhW2uOPAenfvZM-otgyCMVYgYlgVgqG-uVBHTUOacfwi5A== 08:37:14 INFO - x-amz-version-id: rINV6su_sxI2zOZ2S1G4JiZUPESq27Mz 08:37:14 INFO - Expected file size: 16310108 08:37:14 INFO - Obtained file size: 16310108 08:37:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:37:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:37:20 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:37:21 INFO - Http code: 200 08:37:21 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 08:37:21 INFO - x-amz-cf-id: O1BvxkoqvZGVxwOgaWtsk81qcReUz6aWwVZVvTNnOrcNIu3iHy4Lxw== 08:37:21 INFO - x-amz-version-id: mw2.1ojdKM_ntwU1fDsg2xSIi4JskeeH 08:37:22 INFO - Expected file size: 14409038 08:37:22 INFO - Obtained file size: 14409038 08:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:22 INFO - trying https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:37:25 INFO - Downloaded 111582215 bytes. 08:37:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:25 INFO - mkdir: /builds/slave/test/properties 08:37:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:37:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:37:25 INFO - Contents: 08:37:25 INFO - build_url:https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:26 INFO - Running post-action listener: _resource_record_post_action 08:37:26 INFO - Running post-action listener: set_extra_try_arguments 08:37:26 INFO - [mozharness: 2016-11-08 16:37:26.117870Z] Finished download-and-extract step (success) 08:37:26 INFO - [mozharness: 2016-11-08 16:37:26.118003Z] Running populate-webroot step. 08:37:26 INFO - Running pre-action listener: _resource_record_pre_action 08:37:26 INFO - Running main action method: populate_webroot 08:37:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:37:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:37:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:37:26 INFO - '--tppagecycles', 08:37:26 INFO - '7'], 08:37:26 INFO - 'tests': ['tsvgm']}}, 08:37:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:37:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:37:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:37:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:37:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:37:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:37:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['damp', 'tps']}, 08:37:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'tests': ['damp', 'tps']}, 08:37:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['dromaeo_dom']}, 08:37:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:37:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['basic_compositor_video']}, 08:37:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:37:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['a11yr', 08:37:26 INFO - 'ts_paint', 08:37:26 INFO - 'tpaint', 08:37:26 INFO - 'sessionrestore', 08:37:26 INFO - 'sessionrestore_no_auto_restore', 08:37:26 INFO - 'tabpaint']}, 08:37:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['tsvgx', 08:37:26 INFO - 'tsvgr_opacity', 08:37:26 INFO - 'tart', 08:37:26 INFO - 'tscrollx', 08:37:26 INFO - 'cart']}, 08:37:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:37:26 INFO - 'tsvgr_opacity', 08:37:26 INFO - 'tart', 08:37:26 INFO - 'tscrollx', 08:37:26 INFO - 'cart']}, 08:37:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'talos_options': ['--disable-e10s'], 08:37:26 INFO - 'tests': ['tp5o']}, 08:37:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'tests': ['tp5o']}, 08:37:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'talos_options': ['--disable-e10s', 08:37:26 INFO - '--xperf_path', 08:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:37:26 INFO - 'tests': ['tp5n']}, 08:37:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:26 INFO - 'talos_options': ['--xperf_path', 08:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:37:26 INFO - 'tests': ['tp5n']}}, 08:37:26 INFO - 'talos.zip': {'path': '', 08:37:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:37:26 INFO - Running post-action listener: _resource_record_post_action 08:37:26 INFO - [mozharness: 2016-11-08 16:37:26.136705Z] Finished populate-webroot step (success) 08:37:26 INFO - [mozharness: 2016-11-08 16:37:26.136807Z] Running create-virtualenv step. 08:37:26 INFO - Running pre-action listener: _resource_record_pre_action 08:37:26 INFO - Running main action method: create_virtualenv 08:37:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:37:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:37:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:37:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:37:26 INFO - Using real prefix '/tools/python27' 08:37:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:37:26 INFO - Installing distribute.............................................................................................................................................................................................done. 08:37:29 INFO - Installing pip.................done. 08:37:29 INFO - Return code: 0 08:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:37:30 INFO - Reading from file tmpfile_stdout 08:37:30 INFO - Output received: 08:37:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:37:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:30 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:30 INFO - 'HOME': '/Users/cltbld', 08:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:30 INFO - 'LOGNAME': 'cltbld', 08:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:30 INFO - 'MOZ_NO_REMOTE': '1', 08:37:30 INFO - 'NO_EM_RESTART': '1', 08:37:30 INFO - 'PAGER': '/bin/cat', 08:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:30 INFO - 'PWD': '/builds/slave/test', 08:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:30 INFO - 'SHELL': '/bin/bash', 08:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:30 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:30 INFO - 'USER': 'cltbld', 08:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:30 INFO - 'XPC_FLAGS': '0x0', 08:37:30 INFO - 'XPC_SERVICE_NAME': '0', 08:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:37:30 INFO - Cleaning up... 08:37:30 INFO - Return code: 0 08:37:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:30 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:30 INFO - 'HOME': '/Users/cltbld', 08:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:30 INFO - 'LOGNAME': 'cltbld', 08:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:30 INFO - 'MOZ_NO_REMOTE': '1', 08:37:30 INFO - 'NO_EM_RESTART': '1', 08:37:30 INFO - 'PAGER': '/bin/cat', 08:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:30 INFO - 'PWD': '/builds/slave/test', 08:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:30 INFO - 'SHELL': '/bin/bash', 08:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:30 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:30 INFO - 'USER': 'cltbld', 08:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:30 INFO - 'XPC_FLAGS': '0x0', 08:37:30 INFO - 'XPC_SERVICE_NAME': '0', 08:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:30 INFO - Downloading/unpacking psutil>=3.1.1 08:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:37:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:37:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:37:34 INFO - Installing collected packages: psutil 08:37:34 INFO - Running setup.py install for psutil 08:37:34 INFO - building 'psutil._psutil_osx' extension 08:37:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:37:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:37:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:37:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:37:35 INFO - building 'psutil._psutil_posix' extension 08:37:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:37:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:37:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:37:35 INFO - ^ 08:37:35 INFO - 1 warning generated. 08:37:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:37:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:37:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:37:35 INFO - Successfully installed psutil 08:37:35 INFO - Cleaning up... 08:37:35 INFO - Return code: 0 08:37:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:35 INFO - 'HOME': '/Users/cltbld', 08:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:35 INFO - 'LOGNAME': 'cltbld', 08:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:35 INFO - 'MOZ_NO_REMOTE': '1', 08:37:35 INFO - 'NO_EM_RESTART': '1', 08:37:35 INFO - 'PAGER': '/bin/cat', 08:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:35 INFO - 'PWD': '/builds/slave/test', 08:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:35 INFO - 'SHELL': '/bin/bash', 08:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:35 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:35 INFO - 'USER': 'cltbld', 08:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:35 INFO - 'XPC_FLAGS': '0x0', 08:37:35 INFO - 'XPC_SERVICE_NAME': '0', 08:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:37:38 INFO - Installing collected packages: mozsystemmonitor 08:37:38 INFO - Running setup.py install for mozsystemmonitor 08:37:38 INFO - Successfully installed mozsystemmonitor 08:37:38 INFO - Cleaning up... 08:37:38 INFO - Return code: 0 08:37:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:38 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:38 INFO - 'HOME': '/Users/cltbld', 08:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:38 INFO - 'LOGNAME': 'cltbld', 08:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:38 INFO - 'MOZ_NO_REMOTE': '1', 08:37:38 INFO - 'NO_EM_RESTART': '1', 08:37:38 INFO - 'PAGER': '/bin/cat', 08:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:38 INFO - 'PWD': '/builds/slave/test', 08:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:38 INFO - 'SHELL': '/bin/bash', 08:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:38 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:38 INFO - 'USER': 'cltbld', 08:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:38 INFO - 'XPC_FLAGS': '0x0', 08:37:38 INFO - 'XPC_SERVICE_NAME': '0', 08:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:37:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:41 INFO - Downloading functools32-3.2.3-2.tar.gz 08:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:37:41 INFO - warning: no files found matching '*.txt' 08:37:41 INFO - no previously-included directories found matching 'build' 08:37:41 INFO - no previously-included directories found matching 'dist' 08:37:41 INFO - no previously-included directories found matching '.git*' 08:37:41 INFO - Installing collected packages: jsonschema, functools32 08:37:41 INFO - Running setup.py install for functools32 08:37:41 INFO - warning: no files found matching '*.txt' 08:37:41 INFO - no previously-included directories found matching 'build' 08:37:41 INFO - no previously-included directories found matching 'dist' 08:37:41 INFO - no previously-included directories found matching '.git*' 08:37:41 INFO - Successfully installed jsonschema functools32 08:37:41 INFO - Cleaning up... 08:37:41 INFO - Return code: 0 08:37:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:37: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')]} 08:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37: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')]} 08:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:41 INFO - 'HOME': '/Users/cltbld', 08:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:41 INFO - 'LOGNAME': 'cltbld', 08:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:41 INFO - 'MOZ_NO_REMOTE': '1', 08:37:41 INFO - 'NO_EM_RESTART': '1', 08:37:41 INFO - 'PAGER': '/bin/cat', 08:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:41 INFO - 'PWD': '/builds/slave/test', 08:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:41 INFO - 'SHELL': '/bin/bash', 08:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:41 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:41 INFO - 'USER': 'cltbld', 08:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:41 INFO - 'XPC_FLAGS': '0x0', 08:37:41 INFO - 'XPC_SERVICE_NAME': '0', 08:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:37:42 INFO - Cleaning up... 08:37:42 INFO - Return code: 0 08:37:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:42 INFO - 'HOME': '/Users/cltbld', 08:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:42 INFO - 'LOGNAME': 'cltbld', 08:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:42 INFO - 'MOZ_NO_REMOTE': '1', 08:37:42 INFO - 'NO_EM_RESTART': '1', 08:37:42 INFO - 'PAGER': '/bin/cat', 08:37:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:42 INFO - 'PWD': '/builds/slave/test', 08:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:42 INFO - 'SHELL': '/bin/bash', 08:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:42 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:42 INFO - 'USER': 'cltbld', 08:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:42 INFO - 'XPC_FLAGS': '0x0', 08:37:42 INFO - 'XPC_SERVICE_NAME': '0', 08:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:42 INFO - Downloading/unpacking blobuploader==1.2.4 08:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:44 INFO - Downloading blobuploader-1.2.4.tar.gz 08:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:37:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:37:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:46 INFO - Downloading docopt-0.6.1.tar.gz 08:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:37:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:37:46 INFO - Running setup.py install for blobuploader 08:37:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:37:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:37:46 INFO - Running setup.py install for requests 08:37:46 INFO - Running setup.py install for docopt 08:37:46 INFO - Successfully installed blobuploader requests docopt 08:37:46 INFO - Cleaning up... 08:37:47 INFO - Return code: 0 08:37:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37: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')]} 08:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37: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')]} 08:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:47 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:47 INFO - 'HOME': '/Users/cltbld', 08:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:47 INFO - 'LOGNAME': 'cltbld', 08:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:47 INFO - 'MOZ_NO_REMOTE': '1', 08:37:47 INFO - 'NO_EM_RESTART': '1', 08:37:47 INFO - 'PAGER': '/bin/cat', 08:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:47 INFO - 'PWD': '/builds/slave/test', 08:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:47 INFO - 'SHELL': '/bin/bash', 08:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:47 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:47 INFO - 'USER': 'cltbld', 08:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:47 INFO - 'XPC_FLAGS': '0x0', 08:37:47 INFO - 'XPC_SERVICE_NAME': '0', 08:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-xXcrZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-ASEzcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-0fLmmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-6PE4Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-c6dvnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-rnedE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:47 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-3BGBOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-RwYaOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Qr823H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-7fFmwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-1_CEaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-rMIx34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Wr9P7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-tjxYCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-vT1k_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:48 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-mSrYVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:49 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Vx5Ozh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:37:49 INFO - Running setup.py install for manifestparser 08:37:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:37:49 INFO - Running setup.py install for mozcrash 08:37:49 INFO - Running setup.py install for mozdebug 08:37:49 INFO - Running setup.py install for mozdevice 08:37:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:37:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:37:49 INFO - Running setup.py install for mozfile 08:37:49 INFO - Running setup.py install for mozhttpd 08:37:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Running setup.py install for mozinfo 08:37:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Running setup.py install for mozInstall 08:37:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Running setup.py install for mozleak 08:37:50 INFO - Running setup.py install for mozlog 08:37:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Running setup.py install for moznetwork 08:37:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:37:50 INFO - Running setup.py install for mozprocess 08:37:50 INFO - Running setup.py install for mozprofile 08:37:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:37:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:37:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:37:51 INFO - Running setup.py install for mozrunner 08:37:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:37:51 INFO - Running setup.py install for mozscreenshot 08:37:51 INFO - Running setup.py install for moztest 08:37:51 INFO - Running setup.py install for mozversion 08:37:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:37:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:37:51 INFO - Cleaning up... 08:37:51 INFO - Return code: 0 08:37:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:52 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:52 INFO - 'HOME': '/Users/cltbld', 08:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:52 INFO - 'LOGNAME': 'cltbld', 08:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:52 INFO - 'MOZ_NO_REMOTE': '1', 08:37:52 INFO - 'NO_EM_RESTART': '1', 08:37:52 INFO - 'PAGER': '/bin/cat', 08:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:52 INFO - 'PWD': '/builds/slave/test', 08:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:52 INFO - 'SHELL': '/bin/bash', 08:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:52 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:52 INFO - 'USER': 'cltbld', 08:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:52 INFO - 'XPC_FLAGS': '0x0', 08:37:52 INFO - 'XPC_SERVICE_NAME': '0', 08:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:37:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-t8ZsqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:37:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-BnEA7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-qAwXsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-HUsuCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:53 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)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8otPVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-cGAwWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Z_eehG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-25O37D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-i8iO7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-T5mp6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-nPc4B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-yEfPfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:54 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-EpyGxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:54 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-YwMoBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:54 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-JmBRKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:54 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-D0VyRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:54 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oP8XkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:37:54 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)) 08:37:54 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)) 08:37:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:57 INFO - Downloading blessings-1.6.tar.gz 08:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:37:57 INFO - Installing collected packages: blessings 08:37:57 INFO - Running setup.py install for blessings 08:37:57 INFO - Successfully installed blessings 08:37:57 INFO - Cleaning up... 08:37:57 INFO - Return code: 0 08:37:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:37:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:58 INFO - Reading from file tmpfile_stdout 08:37:58 INFO - Current package versions: 08:37:58 INFO - blessings == 1.6 08:37:58 INFO - blobuploader == 1.2.4 08:37:58 INFO - docopt == 0.6.1 08:37:58 INFO - functools32 == 3.2.3-2 08:37:58 INFO - jsonschema == 2.5.1 08:37:58 INFO - manifestparser == 1.1 08:37:58 INFO - mozInstall == 1.12 08:37:58 INFO - mozcrash == 0.17 08:37:58 INFO - mozdebug == 0.1 08:37:58 INFO - mozdevice == 0.48 08:37:58 INFO - mozfile == 1.2 08:37:58 INFO - mozhttpd == 0.7 08:37:58 INFO - mozinfo == 0.9 08:37:58 INFO - mozleak == 0.1 08:37:58 INFO - mozlog == 3.4 08:37:58 INFO - moznetwork == 0.27 08:37:58 INFO - mozprocess == 0.23 08:37:58 INFO - mozprofile == 0.28 08:37:58 INFO - mozrunner == 6.12 08:37:58 INFO - mozscreenshot == 0.1 08:37:58 INFO - mozsystemmonitor == 0.3 08:37:58 INFO - moztest == 0.7 08:37:58 INFO - mozversion == 1.4 08:37:58 INFO - psutil == 3.1.1 08:37:58 INFO - requests == 1.2.3 08:37:58 INFO - wsgiref == 0.1.2 08:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:58 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:58 INFO - 'HOME': '/Users/cltbld', 08:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:58 INFO - 'LOGNAME': 'cltbld', 08:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:58 INFO - 'MOZ_NO_REMOTE': '1', 08:37:58 INFO - 'NO_EM_RESTART': '1', 08:37:58 INFO - 'PAGER': '/bin/cat', 08:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:58 INFO - 'PWD': '/builds/slave/test', 08:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:58 INFO - 'SHELL': '/bin/bash', 08:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:58 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:58 INFO - 'USER': 'cltbld', 08:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:58 INFO - 'XPC_FLAGS': '0x0', 08:37:58 INFO - 'XPC_SERVICE_NAME': '0', 08:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:37:58 INFO - Cleaning up... 08:37:58 INFO - Return code: 0 08:37:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:58 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 0x106f6a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ee0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5e255cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, '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.qeeHlKZZ9r/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.jYdWaH3f82/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 08:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:58 INFO - 'HOME': '/Users/cltbld', 08:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:58 INFO - 'LOGNAME': 'cltbld', 08:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:58 INFO - 'MOZ_NO_REMOTE': '1', 08:37:58 INFO - 'NO_EM_RESTART': '1', 08:37:58 INFO - 'PAGER': '/bin/cat', 08:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:58 INFO - 'PWD': '/builds/slave/test', 08:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:58 INFO - 'SHELL': '/bin/bash', 08:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:37:58 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:58 INFO - 'USER': 'cltbld', 08:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:58 INFO - 'XPC_FLAGS': '0x0', 08:37:58 INFO - 'XPC_SERVICE_NAME': '0', 08:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:37:58 INFO - Cleaning up... 08:37:58 INFO - Return code: 0 08:37:58 INFO - Running post-action listener: _resource_record_post_action 08:37:58 INFO - Running post-action listener: _start_resource_monitoring 08:37:58 INFO - Starting resource monitoring. 08:37:58 INFO - [mozharness: 2016-11-08 16:37:58.726989Z] Finished create-virtualenv step (success) 08:37:58 INFO - [mozharness: 2016-11-08 16:37:58.727967Z] Running install step. 08:37:58 INFO - Running pre-action listener: _resource_record_pre_action 08:37:58 INFO - Running main action method: install 08:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:58 INFO - Reading from file tmpfile_stdout 08:37:58 INFO - Detecting whether we're running mozinstall >=1.0... 08:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:37:59 INFO - Reading from file tmpfile_stdout 08:37:59 INFO - Output received: 08:37:59 INFO - Usage: mozinstall [options] installer 08:37:59 INFO - Options: 08:37:59 INFO - -h, --help show this help message and exit 08:37:59 INFO - -d DEST, --destination=DEST 08:37:59 INFO - Directory to install application into. [default: 08:37:59 INFO - "/builds/slave/test"] 08:37:59 INFO - --app=APP Application being installed. [default: firefox] 08:37:59 INFO - mkdir: /builds/slave/test/build/application 08:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:38:33 INFO - Reading from file tmpfile_stdout 08:38:33 INFO - Output received: 08:38:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:38:33 INFO - Running post-action listener: _resource_record_post_action 08:38:33 INFO - [mozharness: 2016-11-08 16:38:33.661446Z] Finished install step (success) 08:38:33 INFO - [mozharness: 2016-11-08 16:38:33.661585Z] Running run-tests step. 08:38:33 INFO - Running pre-action listener: _resource_record_pre_action 08:38:33 INFO - Running main action method: run_tests 08:38:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:38:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:38:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:38:33 INFO - 2016-11-08 08:38:33.675 screenresolution[1654:7640] starting screenresolution argv=screenresolution get 08:38:33 INFO - 2016-11-08 08:38:33.678 screenresolution[1654:7640] Display 0: 1600x1200x32@60 08:38:33 INFO - 2016-11-08 08:38:33.684 screenresolution[1655:7642] starting screenresolution argv=screenresolution list 08:38:33 INFO - Available Modes on Display 0 08:38:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:38:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:38:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:38:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:38:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:38:33 INFO - Intel Iris: 08:38:33 INFO - Chipset Model: Intel Iris 08:38:33 INFO - Type: GPU 08:38:33 INFO - Bus: Built-In 08:38:33 INFO - VRAM (Dynamic, Max): 1536 MB 08:38:33 INFO - Vendor: Intel (0x8086) 08:38:33 INFO - Device ID: 0x0a2e 08:38:33 INFO - Revision ID: 0x0009 08:38:33 INFO - Displays: 08:38:33 INFO - Display: 08:38:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:38:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:38:33 INFO - Display Serial Number: 1600x1200 60 08:38:33 INFO - Main Display: Yes 08:38:33 INFO - Mirror: Off 08:38:33 INFO - Online: Yes 08:38:33 INFO - Rotation: Supported 08:38:33 INFO - Return code: 0 08:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:38:33 INFO - Python 2.7.3 08:38:33 INFO - Return code: 0 08:38:33 INFO - grabbing minidump binary from tooltool 08:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:33 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 0x106d7ed30>, '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 0x106ba4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d76b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:38:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:38:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:38:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:38:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:38:34 INFO - Return code: 0 08:38:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:38:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:38:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:38:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:38:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.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-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeeHlKZZ9r/Render', 08:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:34 INFO - 'HOME': '/Users/cltbld', 08:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:34 INFO - 'LOGNAME': 'cltbld', 08:38:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:38:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:34 INFO - 'MOZ_NO_REMOTE': '1', 08:38:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:38:34 INFO - 'NO_EM_RESTART': '1', 08:38:34 INFO - 'PAGER': '/bin/cat', 08:38:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:34 INFO - 'PWD': '/builds/slave/test', 08:38:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:34 INFO - 'SHELL': '/bin/bash', 08:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jYdWaH3f82/Listeners', 08:38:34 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 08:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:34 INFO - 'USER': 'cltbld', 08:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:34 INFO - 'XPC_FLAGS': '0x0', 08:38:34 INFO - 'XPC_SERVICE_NAME': '0', 08:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:38:34 INFO - mozversion application_buildid: 20161108062935 08:38:34 INFO - mozversion application_changeset: aea5b4c3d165dcde027b3b6551b146a56748e9be 08:38:34 INFO - mozversion application_display_name: Nightly 08:38:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:38:34 INFO - mozversion application_name: Firefox 08:38:34 INFO - mozversion application_remotingname: firefox 08:38:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:38:34 INFO - mozversion application_vendor: Mozilla 08:38:34 INFO - mozversion application_version: 52.0a1 08:38:34 INFO - mozversion platform_buildid: 20161108062935 08:38:34 INFO - mozversion platform_changeset: aea5b4c3d165dcde027b3b6551b146a56748e9be 08:38:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:38:34 INFO - mozversion platform_version: 52.0a1 08:38:34 INFO - using testdate: 1478623114 08:38:34 INFO - actual date: 1478623114 08:38:34 INFO - starting webserver on 'localhost:49272' 08:38:34 INFO - SUITE-START | Running 2 tests 08:38:34 INFO - TEST-START | dromaeo_css 08:38:34 INFO - operating with platform_type : mac_ 08:38:34 INFO - Initialising browser for dromaeo_css test... 08:38:34 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpbmHE36/profile http://localhost:49272/getInfo.html) 08:38:37 INFO - PROCESS | 1665 | 2016-11-08 08:38:37.006 plugin-container[1667:7820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 08:38:37 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:37 INFO - PROCESS | 1665 | 2016-11-08 08:38:37.009 plugin-container[1667:7820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 08:38:37 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:37 INFO - PROCESS | 1665 | 2016-11-08 08:38:37.009 plugin-container[1667:7820] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:37 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 08:38:37 INFO - PROCESS | 1665 | colorDepth:24 08:38:37 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 08:38:37 INFO - PROCESS | 1665 | __metrics 08:38:37 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 08:38:37 INFO - TEST-INFO | 1665: exit 0 08:38:37 INFO - Browser initialized. 08:38:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:38:37 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpbmHE36/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:40 INFO - PROCESS | 1668 | 2016-11-08 08:38:40.339 plugin-container[1669:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:38:40 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:40 INFO - PROCESS | 1668 | 2016-11-08 08:38:40.339 plugin-container[1669:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:38:40 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:40 INFO - PROCESS | 1668 | 2016-11-08 08:38:40.339 plugin-container[1669:7937] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:50 INFO - PROCESS | 1668 | RSS: Main: 238804992 08:38:50 INFO - PROCESS | 1668 | 08:39:39 INFO - PROCESS | 1668 | 1478623179631 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:41:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 08:41:54 INFO - PROCESS | 1668 | RSS: Main: 252055552 08:41:54 INFO - PROCESS | 1668 | 08:44:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 08:44:57 INFO - PROCESS | 1668 | RSS: Main: 261779456 08:44:57 INFO - PROCESS | 1668 | 08:47:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 08:48:00 INFO - PROCESS | 1668 | RSS: Main: 267145216 08:48:00 INFO - PROCESS | 1668 | 08:51:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 08:51:02 INFO - PROCESS | 1668 | RSS: Main: 266403840 08:51:02 INFO - PROCESS | 1668 | 08:54:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 08:54:06 INFO - PROCESS | 1668 | RSS: Main: 270221312 08:54:06 INFO - PROCESS | 1668 | 08:57:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 08:57:09 INFO - PROCESS | 1668 | RSS: Main: 272781312 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | __start_tp_report 08:57:09 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 08:57:09 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 08:57:09 INFO - PROCESS | 1668 | |i|pagename|runs| 08:57:09 INFO - PROCESS | 1668 | |0;dojo.html;3318.734501239901;3342.916427089323;3366.5455163552915;3368.915777105572;3381.4927760835885;5598.076317407623;5618.325800903887;5623.100303951369;5628.071368224243;5702.600817942745;5556.583256255777;5639.971800141004;5708.686022268774;5735.397783232753;5768.39431859655;5107.131787596099;5156.149235376159;5204.739763011853;5225.451327610604;5259.842204733845;5182.637215394924;5276.15581506959;5285.392179899303;5308.654937429074;5332.893342133155;5131.7434128293435;5167.612429067797;5186.481351864821;5198.2982297389935;5252.395974462947;5183.067047931371;5196.92204616931;5204.271402003723;5209.114450543379;5243.4232234454175;5145.76844042019;5170.069387510247;5171.612129090333;5219.138842091051;5237.162054071368;4696.530346965276;5119.744012799345;5571.749271282762;5606.691631960241;5654.491095801398;6141.393954534099;6180.690965451709;6224;6231.719572619243;6238.9376106238815;6270.87458250837;6278.748850045993;6288.685565721696;6298.338674439164;6310.274318453391;5621.578381621394;5667.093265077568;5684.943150568524;5797.971010144965;5808.30300363959;6216.129741836146;6298.716557754912;6309.905351419732;6311.684415779193;6329.651869147241;4442.755648439367;6070.423309785563;6080.90878636812;6088.208532890784;6095.969520152459;5603.887922241533;6129.570930034854;6183.907241391383;6244.063390491463;6254.499640028786;4712.552307530779;5420.4850539198715;6091.42131497507;6211.3167551569295;6245.344238854946;6166.845828854314;6208.379162083846;6212.72042758077;6230.875382492325;6279.277883043417;4859.173940430135;4896.3389942358035;4963.875903102451;4977.352944117241;4987.825426110068;4382.561743825592;4383.298672212431;4430.867073987785;4452.4211852458975;4495.4605447345875;5655.80204692842;5793.275840519935;5874.882502349929;5875.50058245054;5886.764529418775;5165.9225111623355;6060.727267272983;6194.730080333521;6248.125262463259;6257.74969001244;5056.342675452242;5647.068233741469;5856.385079566669;5880.235569375954;5897.882042359129;4742.1227072991605;4797.280407938837;5365.570754339644;5390.407055223922;5415.323084614423;5353.678779273256;6108.877822443526;6358.427741503287;6361.109444677749;6367.617942923532;6117.492485019964;6173.351798061319;6183.876322473436;6192.473639740672;6215.81352559424;6299.937000630119;6309.179806625109;6337.65142917144;6342.52431067681;6373.3945275198785;6044.607100538451;6131.202943617302;6178.382161783786;6188.752449901954;6211.627302361872;5708.80019199326;5958.076498142795;6164.660943648142;6218.751249950133;6305.96847015762;6100.847478813065;6104.725287362168;6109.877802443837;6121.051237058263;6126.448619624255;5861.208736820475;6106.6336019838955;6115.6330620162935;6122.357152499102;6127.295361033538;5228.424873263862;5783.595148339576;5814.651120932757;5918.141869428822;5978.730957106854;5979.282486101695;6021.277446706424;6041.154238406714;6046;6050.21347224858;5852.882942341044;5856.912146317723;5892.1161825726485;5931.4068593142065;5932.614380065282 08:57:09 INFO - PROCESS | 1668 | |1;ext.html;470.9434867815863;495.67285591509614;514.6628958032488;529.6160283794247;544.244810486182;31694.366112677762;32128;39714.99999999996;39728.404073938844;39847.00382490432;39649.60350396495;40023;40069;40072.998175045635;40152.19695606086;9179.90820091799;9320.161185493303;9388.436693798394;9420.24638028956;9420.481873496956;9298.349115561914;9400.05999400058;9411.576479058458;9422.057794220591;9431.056894310583;9371.95314023429;9395.389299695498;9396.248300135972;9411.670591529299;9422.858657120149;9332.580033898457;9368.812623747555;9370.718878433658;9375.906240937571;9377.202937750299;9020.097990200993;9069.863952040725;9072.8639070414;9078.364514483988;9124.726258212264;38616.80691596552;38768.418473722915;38781;38792.80603596992;38883.61116388828;2783.705576906728;2784.4570308789794;3082.9537556936666;3234.7735658503916;3279.2949515853966;23852;26594.734052659613;29953.700462995526;29980.250493737614;30031.849840750874;2980.249031790975;3137.4352616529018;3159.573457583243;3165.8100513969007;3250.8374581271078;475054;478573;480206;480688.59655701846;482124;60307.69846150829;60589.69705151446;60789.08816367669;60916.69541652352;60968.695156523936;53748.73125634347;54933.90132197334;59299.4770575245;61894;62262;15698.293576789072;15840.970336928292;15913.761293580606;15963.920180399024;15979.360825567079;12085.637430877088;12140.878591214152;12200.755984880252;12253.69365765863;12270.570530031406;14256.501022464165;15894.046357218604;16959.44836644222;18062.729059064124;18893.811061889206;7757.7672669819995;7778.455508114491;7843.9215607844335;7926.454341403486;7976.680932762741;812.6058861452191;915.606289295596;951.8143961927358;974.6345120579782;1002.1682003936716;25345.366365841;27404.45191096167;28533;29409.55885661717;29862;1000.0849222961054;1007.8740157480315;1041.3023274406166;1074.6829685242872;1094.7427354571669;14544.236595046907;15014.174220417983;15445;15462.9226853865;15553.76669349938;646730;652331;652889;653280;654598;55071.72464137734;55072;55251.723741381036;55446;55494.722526387915;1127.9661610151709;1264.759695657822;1328.7209685965863;1335.859734727859;1357.565579014706;22977.195798146986;24572.877135614206;26865;29126.68929898117;29668.703312966594;40376;41945.79027104845;49543;50155;50250.74874625603;594157;601538;602317;602608.7260837549;603613;25869.611955821056;26178.08376706806;26198;26295;26316.473670526866;44026.559734402246;44588.55411445844;44703.10593788171;45458.31812522881;45924.540754592024;37670.81164594269;37745.81127094457;38563.42154867623;38572.421413678254;39244.60755392409;36903.26193476169;36939.44590831086;37806.43290350702;38204;38628.6137138625;32299.51550726694;32682.34635307233;36742.26515469729;36889.63110368862;37143.44284835675;31923.042308730775;32772.180695482806;33867.814626487954;33925.64297428075;33973;24529.386765331008;25241.621375679748;25290.873545632156;25424.363641811204;25993.090241841444 08:57:09 INFO - PROCESS | 1668 | |2;jquery.html;360.59072952199244;363.38950563054067;369.09755647442;378.10012171032935;389.4508742672832;18210.817891821094;18751.718724219132;19256.422307330802;19721.605567888633;19919.800801991976;16783.496495105137;19513;19805.80194198061;20102.798972010274;20308.289209877657;5609.102543593026;5760.847830433909;5787.536997040248;5890.381509941459;5904.55715821313;5118.872028199287;5410.675359478424;5581.469760372758;5723.399043100477;5756.251687280647;4589.426321709786;5699.2306038684865;5714.71426428679;5721.656700597957;5736.913946290809;5274.894502109974;5619.690916999563;5646.096624540054;5755.9424405756035;5760.366359700451;4679.532046795327;4947.826826061107;5039.672421292605;5046.646734728571;5048.318477005594;23235.651465228035;23318.533629327318;23887.522249555084;24151.758482415127;24326.756732432626;2351.870647114408;2514.911978080777;2557.974420255792;2614.3986883017014;2799.908035866014;9536.809263814754;9753.756156096155;9766.755831104208;10267.127294180003;10311.639092631796;1752.7195648696274;1754.1317048061292;1756.0341812003476;1767.805541390446;1871.6631006418838;102073.48963255211;110384.34423483654;110613.44693276483;111710.21650778102;112044;9390.67132650354;9927.453990030474;9968.700938971842;10027.548760305657;10049.648262310784;12069.939650301869;12306.261624302391;12368.1960672556;12376;12380.442880070419;7697.384209263246;7865.174156713462;7907.802304942423;7987.960060199662;8019.198080192051;8153.8776918346275;8180.100188979208;8181.550014749127;8288.58557072144;8352.413041522213;3322.1030321812973;3487.8081705505933;3604.8824864292155;3832.3485007548784;3910.159315747071;1672.3143511160365;1886.2455017992913;1947.9610407791765;1954.7813231981804;1978.3273686946507;711.7615349292195;834.5395557773895;849.7450764770545;934.5467448287575;1011.4993078426226;15862.04827710318;17421;19484.80515194858;19583.804161958484;20517;788.2826627768703;890.617034675096;897.1611543207122;936.3679516326453;960.9039096090335;8473.279771219391;10088.293819432716;10646.307989980627;10657.520411581498;10701.518431670593;586323;591363;595151;605514;607716;126742.36628816981;128133.71866281219;128162.35918820347;130381.34809325893;134809;938.9812203755887;947.190152419685;968.7820240445843;971.1114330387758;975.453745902297;18004.639907202043;18050.27798888082;18233.4529964096;18240.270389184283;18408.171632276313;55289.1706624409;56409;57173;57836.71081644733;58139.70930145492;64230;81860.59069704614;83022.58488707518;83389.58305208434;83949;7801.648925798239;7919.336939242824;8016.118226995142;8338;8372.958135209285;12265.080118990933;12701.237925724487;13013.21920684762;13129.540466083641;13389.794918457386;11087.669479662492;11098.389588572705;11336.263142895685;11408.372539510407;11504.53981840717;11169.218154729091;11178.888211117785;11241.60654377093;11648.534058637559;11786.351750653796;10686.839697404701;10750.08624266946;11199;11240.988311052044;11742.765144697229;6806.897896531655;8132.3900707448365;8187.672493100209;8443.986721593448;8564.143585641636;10042.297039207186;10931.125509959344;11268.211225214156;11291.266067705574;11332.376719280519 08:57:09 INFO - PROCESS | 1668 | |3;mootools.html;1422.1822452090048;1460.4450308882626;1643.852053315202;1768.2396569475136;1798.5863251452165;7576.090869095702;7825.726099586515;8114.959425202873;8603.483790972547;8604.268637165833;8042.758717238492;8335.083140854516;8400.369972252074;8411.411201215917;8461.661533538667;2299.7700229977036;2324.418895276181;2330.07961855068;2330.533893221354;2338.1115176233006;2297.0926484038796;2352.9176478823247;2353.211674089182;2355.128602417108;2360.079568968104;2277.3168049585142;2277.3737222263844;2294.403455101677;2314.259436980167;2321.3732292281074;2278.031836469502;2282.0643536150264;2302.4243939015246;2305.101010605865;2309.5842748305213;2303.792658660712;2320.9071637134493;2330.4406942333885;2334.8248881333966;2335.322756400642;8376.288015518689;8444.028936672239;8471.06818249992;8504.914950850474;8541.359398045171;5096.108181068298;5218.13900706383;5250.44870288618;5261.263423120766;5295.4704529547125;3237.546743455918;3404.063882432351;3542.8937131886087;3614.7831130132276;3617;985.635314933472;1018.3279035836313;1039.7764480636624;1103.773726386097;1105.8064838653204;10083.899161008443;10431.5827366906;10879;11012.50443730034;11052.834207486814;4408.7575183365225;4543.454785425704;4596.954030459653;4636.281376386665;4661.673682842236;9399.389039712396;9420.434773913586;9437.009114043063;9446.952765236267;9466;5181.248718935733;5206.739663016834;5208.218767184877;5217.556507696812;5221.373435187726;4311.913761724748;4319.632831209383;4732.432108147044;4785.784639691222;4794.95205047952;3701.9629803702155;3735.159589092487;3796.468494410785;3820.9617903820604;3834.2331533693373;1253.147859455579;1260.697432616184;1267.7211013576998;1274.0381012335813;1278.2586100061942;651.7686221391433;789.5499565247753;850.0139837788136;867.8611422172422;872.7556284240422;5640.887182256414;5832;5891.734871930774;6057.061155520902;6150.261968563712;736.6574542837499;891.7993451473367;924.0851556958697;960.8750862387844;975.5463709375175;3795.906841252582;3837.2517359114704;3881.3595756700392;3935.7244992850797;3969.1069509360163;9168.908310916939;9932.851007234753;9950.805903291506;10326.070653641058;10386.636467723594;9587;10101.646442374482;10350.637727679496;10358.119559837498;10370;7661.61691915402;7987.321077708344;7988.640511177011;7999.240072193133;8006.639701213459;5461.044317244387;5628.212050313042;5672.829815105553;5717.971410142923;5744.138379243147;9437.858432123661;9576.329656924227;9588.328816982745;9588.76028099303;9602.135807777333;8292.419530632798;9233.984261731339;9573.521323933635;9714.75713107178;9799.85300220483;6737.9326206737305;6768.830779230559;6794.082798822095;6833.282505336868;6841.62371069596;7780.066392032993;7889.960550197212;7892.210778922292;7913.287804097658;7923.326517246099;7014.193367762773;7023.719051238098;7092.680829362795;7286.817829554304;7313.744018959524;6980.581165130108;7102.893456598052;7134.072570565793;7212.9803654976695;7224;6610.504212184202;6639.800805975829;6648.335166483506;6685.966570167118;6697.866042679217;4803.903921921472;4845.176320025675;4867.58625516835;4894.1679914415345;4971.502849715;4512.278035514302;4784.569388755029;4808.182608956556;4863.878403039811;4939.357883475125 08:57:09 INFO - PROCESS | 1668 | |4;prototype.html;297.4823806576556;302.73359443689554;308.14336145515466;310.51249538224994;313.2872714574343;4124.670026397888;4563.338315944186;5034.1190291698895;5287.92068118978;5523.972380138093;4177.707560470761;4526.619381088774;4958.760309922511;5037.899242015166;5116.641835071547;3423.965760342402;3746.9437958430644;4102.979485102585;4112.568180341066;4127.2570937231285;3578.92842143157;3703.8703645372416;3782.9621703782886;4131.586841315874;4159.23054234966;4045.5145382554133;4064.471618689581;4088.7342322749073;4112.2186784511;4119.711620186573;3464.6535346465403;3986.3223252047223;4044.635982761551;4066.1054567995143;4072.348424252135;3422.408580010307;3566.251087271676;3572.7677700949357;3592.892213233581;3627.963720362789;4754.904901901978;5097.5667068299235;5168.8449346519665;5220.660657057279;5243.737813109329;3886.319894018564;4585.358049873021;4591.977040114778;4597.172508948388;4606.355110284563;5223.869403264911;5556.86107847303;5600.243967064435;5610.775568977236;5621.718914054321;3580.337637537064;3807.2956503047026;3809.9619003809885;3829.5213098362706;3841.42378643203;5121.539061484485;5736.397678243766;5764.423557644286;5783.2192653992015;5803.245578074865;2964.3330250693853;3373.5445714828675;3722.906927326784;3733.5893051764283;3856.1902000580194;3139.7645176611845;3780.31954248238;3888.572257051722;3932.9213415731524;3954.9604503954592;3909.7263191576885;3980;4518.186726389282;4528.954710452919;4539.182947069493;5574.386817450078;5592.86017849557;5610.7194640268635;5610.97194514033;5632.381447213328;1121.3440137519478;1145.8255288329528;1239.609523000252;1269.416068608432;1274.2099898063261;783.9804004899809;813.3493205435627;823.8393513264856;844.742353582151;847.6100993543039;4273.594008569181;4565.817367305337;4860.826750027445;5163.741812909339;5171.689698618095;4738.763061846894;4754.405699287589;5357.303550538483;5389.8113566025;5559.444055594408;4479.686421950498;5063.999720110536;5109.014968324713;5142.614303927146;5149.76826042829;2883.423315336941;2892.840893750864;2896.9275768105545;2909.941801163965;2922.6638936522145;5136.5377116059735;5640.7743690252755;5774.162746401748;5784.88430231393;6149.877002459926;6108.480779133734;6119.847003824851;6158.753649854045;6195.287541932646;6239.625622462667;4888.804447822047;6147.293061297919;6181.8454538636;6217;6217.378262173746;5097.980403919157;5394.0290747665795;5691.345495268097;5873.530117590497;5947.226860508106;5238.590248485587;5545.833624991256;5747.712614369181;5802.593818432669;5827;5382.865428364165;5451.088937264255;6036.9698151508965;6109.725062372293;6139.72371243304;3552.3783337915243;4018.65841403484;4039.3335099709393;4040.575739547305;4071.633552980246;5114.181730923035;5349.545288650508;5842.970785146047;5848.970755146197;5877.03300272323;4586.743412177391;5288.629795914249;5461.787975869779;5670.829875103754;5721.599488035798;4496.595306422438;4556.65825063115;4863.90272194547;5709.48698594881;5712.5721568332465;4694.976525117352;4785.401824771903;5267.312092237835;5375.333646569576;5590.183190463034;5656.038473459604;5669.829905102853;5679.971600141973;5698.572607054571;5720.427957204246;4918.6065114791445;5012.273220383097;5203.505666961632;5262.6843289177705;5328.200769884394 08:57:09 INFO - PROCESS | 1668 | |5;yui.html;648.9383508566688;657.8454063295122;680.7209044291841;688.6006116452459;745.8769303064993;13984.37070331835;14803.629909252275;14804.925975370108;14844.77732834007;14846.035007724504;13143.342832858367;13192.208467491957;13334.266615336153;13337.266450345227;13357.465701371957;776.8523980443712;799.6521513141773;802.2579114319232;807.9353651707879;809.0372456776417;805.6334367862638;808.9555074470902;809.1423091522975;811.6428771340621;812.2202685422002;835.4653022065883;846.7967687754956;848.5629900601195;850.587465079436;851.1190917400461;827.9213474719892;832.5753865528594;845.20550682359;846.090452763282;846.9534175620338;624.0857144283601;628.3810446709987;628.4909223528956;630.5586089737202;631.119588174494;13864.445422183202;14093.506727264497;14233.78649320261;14450.277486125651;14455.13269203851;12303;13199.208047517084;13253.13854599448;13320.333983300796;13679.460931443675;10526.578936842518;11298.548058077668;11343;11511.59709410175;11641.6507504775;3527.5414196154593;3565.108722819295;3702.426123950765;3725.515682961198;3737.4767532545466;86168;86321;87764.56117719371;90995;91647;10205.285630005978;10226.897731022744;10273.280870339155;10341.534630941625;10633.30883492586;19736.210551577777;19983.900080499505;20015.699764503544;20029.399118026482;20238.88686122248;7458.627068646546;7489.775306740807;7528.069114729751;7600.9619951900995;7638.809029774189;4183.372494125844;4212.684048696298;4277.914441711148;4403.581659742384;4430.269005614037;2904.8547572621283;3242.8054316741072;3273.8199399032806;3320.070380293522;3328.334333133383;609.1745684597396;617.1206031405229;626.5301024231826;629.4146443807304;638.4573112854036;520.9713465759344;541.3666010767412;569.6496654557417;582.6445868020504;601.9849503762354;8497;8631.352648551383;8809.823803523894;8838.248748856418;8992.370534062684;575.7754475754459;586.5366360575183;614.0911451052467;616.7717944360616;631.7522892287695;3467.826608669556;3471.5660542432197;4052.2908491014546;4160.604742549453;4811.879703007383;80395;115311.42344288225;119345;119485;125043;59989.70005150034;61771;70365;83684.58157709173;104217.43673844899;73300.63349683218;73994;77344;86181;86856.56571716974;9069;9538.904610953801;9564.234861210947;9597.28020398459;9774.75563110928;41717.165656687306;51196.74401627968;51533;51802;52964.73517632387;70223;72387;75133;76258;77082.2291777075;12125;12238.265704057785;12292.38538073075;12689.36553172356;12862.871371286168;13114.770484020015;13884.930575347058;14021.3690383935;14247.928760356132;14475.27623618836;12904.677383065124;13018.609441716764;13150.868491314965;13244.735105297646;13767.31163441844;10180.440075795903;13090.345482725636;13189.802152967904;13374.665633359244;13703.383347749575;10889.536186421816;12442.626721198378;12657.367131643565;13177.868221317663;13253.734925301633;10530.631427899987;10607.681769546925;10674.46627668629;11008.39453830015;11343.716407089889;10981.231313807957;11165.715942666697;11251.887481125412;11369.54521819118;11403.258788178742 08:57:09 INFO - PROCESS | 1668 | __end_tp_report 08:57:09 INFO - PROCESS | 1668 | __start_cc_report 08:57:09 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,379 08:57:09 INFO - PROCESS | 1668 | __end_cc_report 08:57:09 INFO - PROCESS | 1668 | __startTimestamp1478624229089__endTimestamp 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | ------- Summary: start ------- 08:57:09 INFO - PROCESS | 1668 | Number of tests: 6 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5667.07 Median:5861.21 stddev:654.41 (11.2%) stddev-sans-first:629.96 08:57:09 INFO - PROCESS | 1668 | Values: 3318.7 3342.9 3366.5 3368.9 3381.5 5598.1 5618.3 5623.1 5628.1 5702.6 5556.6 5640.0 5708.7 5735.4 5768.4 5107.1 5156.1 5204.7 5225.5 5259.8 5182.6 5276.2 5285.4 5308.7 5332.9 5131.7 5167.6 5186.5 5198.3 5252.4 5183.1 5196.9 5204.3 5209.1 5243.4 5145.8 5170.1 5171.6 5219.1 5237.2 4696.5 5119.7 5571.7 5606.7 5654.5 6141.4 6180.7 6224.0 6231.7 6238.9 6270.9 6278.7 6288.7 6298.3 6310.3 5621.6 5667.1 5684.9 5798.0 5808.3 6216.1 6298.7 6309.9 6311.7 6329.7 4442.8 6070.4 6080.9 6088.2 6096.0 5603.9 6129.6 6183.9 6244.1 6254.5 4712.6 5420.5 6091.4 6211.3 6245.3 6166.8 6208.4 6212.7 6230.9 6279.3 4859.2 4896.3 4963.9 4977.4 4987.8 4382.6 4383.3 4430.9 4452.4 4495.5 5655.8 5793.3 5874.9 5875.5 5886.8 5165.9 6060.7 6194.7 6248.1 6257.7 5056.3 5647.1 5856.4 5880.2 5897.9 4742.1 4797.3 5365.6 5390.4 5415.3 5353.7 6108.9 6358.4 6361.1 6367.6 6117.5 6173.4 6183.9 6192.5 6215.8 6299.9 6309.2 6337.7 6342.5 6373.4 6044.6 6131.2 6178.4 6188.8 6211.6 5708.8 5958.1 6164.7 6218.8 6306.0 6100.8 6104.7 6109.9 6121.1 6126.4 5861.2 6106.6 6115.6 6122.4 6127.3 5228.4 5783.6 5814.7 5918.1 5978.7 5979.3 6021.3 6041.2 6046.0 6050.2 5852.9 5856.9 5892.1 5931.4 5932.6 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:69905.35 Median:38838.21 stddev:155806.10 (401.2%) stddev-sans-first:156155.96 08:57:09 INFO - PROCESS | 1668 | Values: 470.9 495.7 514.7 529.6 544.2 31694.4 32128.0 39715.0 39728.4 39847.0 39649.6 40023.0 40069.0 40073.0 40152.2 9179.9 9320.2 9388.4 9420.2 9420.5 9298.3 9400.1 9411.6 9422.1 9431.1 9372.0 9395.4 9396.2 9411.7 9422.9 9332.6 9368.8 9370.7 9375.9 9377.2 9020.1 9069.9 9072.9 9078.4 9124.7 38616.8 38768.4 38781.0 38792.8 38883.6 2783.7 2784.5 3083.0 3234.8 3279.3 23852.0 26594.7 29953.7 29980.3 30031.8 2980.2 3137.4 3159.6 3165.8 3250.8 475054.0 478573.0 480206.0 480688.6 482124.0 60307.7 60589.7 60789.1 60916.7 60968.7 53748.7 54933.9 59299.5 61894.0 62262.0 15698.3 15841.0 15913.8 15963.9 15979.4 12085.6 12140.9 12200.8 12253.7 12270.6 14256.5 15894.0 16959.4 18062.7 18893.8 7757.8 7778.5 7843.9 7926.5 7976.7 812.6 915.6 951.8 974.6 1002.2 25345.4 27404.5 28533.0 29409.6 29862.0 1000.1 1007.9 1041.3 1074.7 1094.7 14544.2 15014.2 15445.0 15462.9 15553.8 646730.0 652331.0 652889.0 653280.0 654598.0 55071.7 55072.0 55251.7 55446.0 55494.7 1128.0 1264.8 1328.7 1335.9 1357.6 22977.2 24572.9 26865.0 29126.7 29668.7 40376.0 41945.8 49543.0 50155.0 50250.7 594157.0 601538.0 602317.0 602608.7 603613.0 25869.6 26178.1 26198.0 26295.0 26316.5 44026.6 44588.6 44703.1 45458.3 45924.5 37670.8 37745.8 38563.4 38572.4 39244.6 36903.3 36939.4 37806.4 38204.0 38628.6 32299.5 32682.3 36742.3 36889.6 37143.4 31923.0 32772.2 33867.8 33925.6 33973.0 24529.4 25241.6 25290.9 25424.4 25993.1 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34554.94 Median:1924.20 stddev:99674.43 (5180.0%) stddev-sans-first:99920.98 08:57:09 INFO - PROCESS | 1668 | Values: 360.6 363.4 369.1 378.1 389.5 18210.8 18751.7 19256.4 19721.6 19919.8 16783.5 19513.0 19805.8 20102.8 20308.3 5609.1 5760.8 5787.5 5890.4 5904.6 5118.9 5410.7 5581.5 5723.4 5756.3 4589.4 5699.2 5714.7 5721.7 5736.9 5274.9 5619.7 5646.1 5755.9 5760.4 4679.5 4947.8 5039.7 5046.6 5048.3 23235.7 23318.5 23887.5 24151.8 24326.8 2351.9 2514.9 2558.0 2614.4 2799.9 9536.8 9753.8 9766.8 10267.1 10311.6 1752.7 1754.1 1756.0 1767.8 1871.7 102073.5 110384.3 110613.4 111710.2 112044.0 9390.7 9927.5 9968.7 10027.5 10049.6 12069.9 12306.3 12368.2 12376.0 12380.4 7697.4 7865.2 7907.8 7988.0 8019.2 8153.9 8180.1 8181.6 8288.6 8352.4 3322.1 3487.8 3604.9 3832.3 3910.2 1672.3 1886.2 1948.0 1954.8 1978.3 711.8 834.5 849.7 934.5 1011.5 15862.0 17421.0 19484.8 19583.8 20517.0 788.3 890.6 897.2 936.4 960.9 8473.3 10088.3 10646.3 10657.5 10701.5 586323.0 591363.0 595151.0 605514.0 607716.0 126742.4 128133.7 128162.4 130381.3 134809.0 939.0 947.2 968.8 971.1 975.5 18004.6 18050.3 18233.5 18240.3 18408.2 55289.2 56409.0 57173.0 57836.7 58139.7 64230.0 81860.6 83022.6 83389.6 83949.0 7801.6 7919.3 8016.1 8338.0 8373.0 12265.1 12701.2 13013.2 13129.5 13389.8 11087.7 11098.4 11336.3 11408.4 11504.5 11169.2 11178.9 11241.6 11648.5 11786.4 10686.8 10750.1 11199.0 11241.0 11742.8 6806.9 8132.4 8187.7 8444.0 8564.1 10042.3 10931.1 11268.2 11291.3 11332.4 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5465.89 Median:5219.76 stddev:2985.70 (57.2%) stddev-sans-first:2978.61 08:57:09 INFO - PROCESS | 1668 | Values: 1422.2 1460.4 1643.9 1768.2 1798.6 7576.1 7825.7 8115.0 8603.5 8604.3 8042.8 8335.1 8400.4 8411.4 8461.7 2299.8 2324.4 2330.1 2330.5 2338.1 2297.1 2352.9 2353.2 2355.1 2360.1 2277.3 2277.4 2294.4 2314.3 2321.4 2278.0 2282.1 2302.4 2305.1 2309.6 2303.8 2320.9 2330.4 2334.8 2335.3 8376.3 8444.0 8471.1 8504.9 8541.4 5096.1 5218.1 5250.4 5261.3 5295.5 3237.5 3404.1 3542.9 3614.8 3617.0 985.6 1018.3 1039.8 1103.8 1105.8 10083.9 10431.6 10879.0 11012.5 11052.8 4408.8 4543.5 4597.0 4636.3 4661.7 9399.4 9420.4 9437.0 9447.0 9466.0 5181.2 5206.7 5208.2 5217.6 5221.4 4311.9 4319.6 4732.4 4785.8 4795.0 3702.0 3735.2 3796.5 3821.0 3834.2 1253.1 1260.7 1267.7 1274.0 1278.3 651.8 789.5 850.0 867.9 872.8 5640.9 5832.0 5891.7 6057.1 6150.3 736.7 891.8 924.1 960.9 975.5 3795.9 3837.3 3881.4 3935.7 3969.1 9168.9 9932.9 9950.8 10326.1 10386.6 9587.0 10101.6 10350.6 10358.1 10370.0 7661.6 7987.3 7988.6 7999.2 8006.6 5461.0 5628.2 5672.8 5718.0 5744.1 9437.9 9576.3 9588.3 9588.8 9602.1 8292.4 9234.0 9573.5 9714.8 9799.9 6737.9 6768.8 6794.1 6833.3 6841.6 7780.1 7890.0 7892.2 7913.3 7923.3 7014.2 7023.7 7092.7 7286.8 7313.7 6980.6 7102.9 7134.1 7213.0 7224.0 6610.5 6639.8 6648.3 6686.0 6697.9 4803.9 4845.2 4867.6 4894.2 4971.5 4512.3 4784.6 4808.2 4863.9 4939.4 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4490.41 Median:5023.20 stddev:1415.31 (28.2%) stddev-sans-first:1383.85 08:57:09 INFO - PROCESS | 1668 | Values: 297.5 302.7 308.1 310.5 313.3 4124.7 4563.3 5034.1 5287.9 5524.0 4177.7 4526.6 4958.8 5037.9 5116.6 3424.0 3746.9 4103.0 4112.6 4127.3 3578.9 3703.9 3783.0 4131.6 4159.2 4045.5 4064.5 4088.7 4112.2 4119.7 3464.7 3986.3 4044.6 4066.1 4072.3 3422.4 3566.3 3572.8 3592.9 3628.0 4754.9 5097.6 5168.8 5220.7 5243.7 3886.3 4585.4 4592.0 4597.2 4606.4 5223.9 5556.9 5600.2 5610.8 5621.7 3580.3 3807.3 3810.0 3829.5 3841.4 5121.5 5736.4 5764.4 5783.2 5803.2 2964.3 3373.5 3722.9 3733.6 3856.2 3139.8 3780.3 3888.6 3932.9 3955.0 3909.7 3980.0 4518.2 4529.0 4539.2 5574.4 5592.9 5610.7 5611.0 5632.4 1121.3 1145.8 1239.6 1269.4 1274.2 784.0 813.3 823.8 844.7 847.6 4273.6 4565.8 4860.8 5163.7 5171.7 4738.8 4754.4 5357.3 5389.8 5559.4 4479.7 5064.0 5109.0 5142.6 5149.8 2883.4 2892.8 2896.9 2909.9 2922.7 5136.5 5640.8 5774.2 5784.9 6149.9 6108.5 6119.8 6158.8 6195.3 6239.6 4888.8 6147.3 6181.8 6217.0 6217.4 5098.0 5394.0 5691.3 5873.5 5947.2 5238.6 5545.8 5747.7 5802.6 5827.0 5382.9 5451.1 6037.0 6109.7 6139.7 3552.4 4018.7 4039.3 4040.6 4071.6 5114.2 5349.5 5843.0 5849.0 5877.0 4586.7 5288.6 5461.8 5670.8 5721.6 4496.6 4556.7 4863.9 5709.5 5712.6 4695.0 4785.4 5267.3 5375.3 5590.2 5656.0 5669.8 5680.0 5698.6 5720.4 4918.6 5012.3 5203.5 5262.7 5328.2 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:19756.37 Median:4198.03 stddev:28842.14 (687.0%) stddev-sans-first:28887.36 08:57:09 INFO - PROCESS | 1668 | Values: 648.9 657.8 680.7 688.6 745.9 13984.4 14803.6 14804.9 14844.8 14846.0 13143.3 13192.2 13334.3 13337.3 13357.5 776.9 799.7 802.3 807.9 809.0 805.6 809.0 809.1 811.6 812.2 835.5 846.8 848.6 850.6 851.1 827.9 832.6 845.2 846.1 847.0 624.1 628.4 628.5 630.6 631.1 13864.4 14093.5 14233.8 14450.3 14455.1 12303.0 13199.2 13253.1 13320.3 13679.5 10526.6 11298.5 11343.0 11511.6 11641.7 3527.5 3565.1 3702.4 3725.5 3737.5 86168.0 86321.0 87764.6 90995.0 91647.0 10205.3 10226.9 10273.3 10341.5 10633.3 19736.2 19983.9 20015.7 20029.4 20238.9 7458.6 7489.8 7528.1 7601.0 7638.8 4183.4 4212.7 4277.9 4403.6 4430.3 2904.9 3242.8 3273.8 3320.1 3328.3 609.2 617.1 626.5 629.4 638.5 521.0 541.4 569.6 582.6 602.0 8497.0 8631.4 8809.8 8838.2 8992.4 575.8 586.5 614.1 616.8 631.8 3467.8 3471.6 4052.3 4160.6 4811.9 80395.0 115311.4 119345.0 119485.0 125043.0 59989.7 61771.0 70365.0 83684.6 104217.4 73300.6 73994.0 77344.0 86181.0 86856.6 9069.0 9538.9 9564.2 9597.3 9774.8 41717.2 51196.7 51533.0 51802.0 52964.7 70223.0 72387.0 75133.0 76258.0 77082.2 12125.0 12238.3 12292.4 12689.4 12862.9 13114.8 13884.9 14021.4 14247.9 14475.3 12904.7 13018.6 13150.9 13244.7 13767.3 10180.4 13090.3 13189.8 13374.7 13703.4 10889.5 12442.6 12657.4 13177.9 13253.7 10530.6 10607.7 10674.5 11008.4 11343.7 10981.2 11165.7 11251.9 11369.5 11403.3 08:57:09 INFO - PROCESS | 1668 | -------- Summary: end -------- 08:57:09 INFO - PROCESS | 1668 | 08:57:09 INFO - TEST-INFO | 1668: exit 0 08:57:09 INFO - TEST-OK | dromaeo_css | took 1115427ms 08:57:09 INFO - TEST-START | kraken 08:57:09 INFO - operating with platform_type : mac_ 08:57:09 INFO - Initialising browser for kraken test... 08:57:09 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpyGFfZT/profile http://localhost:49272/getInfo.html) 08:57:12 INFO - PROCESS | 1680 | 2016-11-08 08:57:12.473 plugin-container[1681:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474f, name = 'com.apple.tsm.portname' 08:57:12 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:12 INFO - PROCESS | 1680 | 2016-11-08 08:57:12.476 plugin-container[1681:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:57:12 INFO - PROCESS | 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:12 INFO - PROCESS | 1680 | 2016-11-08 08:57:12.476 plugin-container[1681:10355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:12 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 08:57:12 INFO - PROCESS | 1680 | colorDepth:24 08:57:12 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 08:57:12 INFO - PROCESS | 1680 | __metrics 08:57:12 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 08:57:13 INFO - TEST-INFO | 1680: exit 0 08:57:13 INFO - Browser initialized. 08:57:13 INFO - Running cycle 1/1 for kraken test... 08:57:13 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpyGFfZT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:15 INFO - PROCESS | 1682 | 2016-11-08 08:57:15.856 plugin-container[1683:10472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:57:15 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:15 INFO - PROCESS | 1682 | 2016-11-08 08:57:15.856 plugin-container[1683:10472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:57:15 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:15 INFO - PROCESS | 1682 | 2016-11-08 08:57:15.856 plugin-container[1683:10472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:25 INFO - PROCESS | 1682 | RSS: Main: 238358528 08:57:25 INFO - PROCESS | 1682 | 08:57:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:29 INFO - PROCESS | 1682 | RSS: Main: 250929152 08:57:29 INFO - PROCESS | 1682 | 08:57:32 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 08:57:32 INFO - PROCESS | 1682 | RSS: Main: 259547136 08:57:32 INFO - PROCESS | 1682 | 08:57:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 08:57:36 INFO - PROCESS | 1682 | RSS: Main: 261562368 08:57:36 INFO - PROCESS | 1682 | 08:57:38 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 08:57:39 INFO - PROCESS | 1682 | RSS: Main: 262471680 08:57:39 INFO - PROCESS | 1682 | 08:57:41 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:57:42 INFO - PROCESS | 1682 | RSS: Main: 262823936 08:57:42 INFO - PROCESS | 1682 | 08:57:45 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 08:57:45 INFO - PROCESS | 1682 | RSS: Main: 263688192 08:57:45 INFO - PROCESS | 1682 | 08:57:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 08:57:49 INFO - PROCESS | 1682 | RSS: Main: 265203712 08:57:49 INFO - PROCESS | 1682 | 08:57:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 08:57:53 INFO - PROCESS | 1682 | RSS: Main: 265428992 08:57:53 INFO - PROCESS | 1682 | 08:57:54 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:57:55 INFO - PROCESS | 1682 | RSS: Main: 265097216 08:57:55 INFO - PROCESS | 1682 | 08:57:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:57:58 INFO - PROCESS | 1682 | RSS: Main: 265142272 08:57:58 INFO - PROCESS | 1682 | 08:58:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:58:01 INFO - PROCESS | 1682 | RSS: Main: 265187328 08:58:01 INFO - PROCESS | 1682 | 08:58:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:58:04 INFO - PROCESS | 1682 | RSS: Main: 265519104 08:58:04 INFO - PROCESS | 1682 | 08:58:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:58:08 INFO - PROCESS | 1682 | RSS: Main: 265297920 08:58:08 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 08:58:10 INFO - PROCESS | 1682 | RSS: Main: 267005952 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | __start_tp_report 08:58:10 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 08:58:10 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 08:58:10 INFO - PROCESS | 1682 | |i|pagename|runs| 08:58:10 INFO - PROCESS | 1682 | |0;ai-astar;116;119;118;118;118;118;118;118;118;118 08:58:10 INFO - PROCESS | 1682 | |1;audio-beat-detection;183;114;114;146;112;130;116;117;141;115 08:58:10 INFO - PROCESS | 1682 | |2;audio-dft;192;198;238;230;196;270;241;219;216;258 08:58:10 INFO - PROCESS | 1682 | |3;audio-fft;83;58;68;111;86;72;62;59;81;82 08:58:10 INFO - PROCESS | 1682 | |4;audio-oscillator;86;72;90;74;71;72;88;70;73;90 08:58:10 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;90;86;89;87;90;74;135;88;133;132 08:58:10 INFO - PROCESS | 1682 | |6;imaging-darkroom;153;96;131;153;94;97;96;98;150;100 08:58:10 INFO - PROCESS | 1682 | |7;imaging-desaturate;138;136;85;111;113;89;112;91;88;90 08:58:10 INFO - PROCESS | 1682 | |8;json-parse-financial;59;65;78;86;91;83;59;59;59;61 08:58:10 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;70;77;80;80;79;70;93;104;99;73 08:58:10 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;101;115;112;109;88;91;87;90;87;93 08:58:10 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;156;168;165;162;154;149;151;152;197;152 08:58:10 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;177;180;178;177;181;175;178;180;177;202 08:58:10 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;69;71;68;67;71;71;90;92;72;68 08:58:10 INFO - PROCESS | 1682 | __end_tp_report 08:58:10 INFO - PROCESS | 1682 | __start_cc_report 08:58:10 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,950 08:58:10 INFO - PROCESS | 1682 | __end_cc_report 08:58:10 INFO - PROCESS | 1682 | __startTimestamp1478624290357__endTimestamp 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | ------- Summary: start ------- 08:58:10 INFO - PROCESS | 1682 | Number of tests: 14 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:117.90 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.33 08:58:10 INFO - PROCESS | 1682 | Values: 116.0 119.0 118.0 118.0 118.0 118.0 118.0 118.0 118.0 118.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:123.50 stddev:22.60 (18.3%) stddev-sans-first:12.91 08:58:10 INFO - PROCESS | 1682 | Values: 183.0 114.0 114.0 146.0 112.0 130.0 116.0 117.0 141.0 115.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:225.80 Median:234.00 stddev:26.53 (11.3%) stddev-sans-first:25.16 08:58:10 INFO - PROCESS | 1682 | Values: 192.0 198.0 238.0 230.0 196.0 270.0 241.0 219.0 216.0 258.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:76.20 Median:76.50 stddev:16.07 (21.0%) stddev-sans-first:16.85 08:58:10 INFO - PROCESS | 1682 | Values: 83.0 58.0 68.0 111.0 86.0 72.0 62.0 59.0 81.0 82.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:80.00 stddev:8.66 (10.8%) stddev-sans-first:8.76 08:58:10 INFO - PROCESS | 1682 | Values: 86.0 72.0 90.0 74.0 71.0 72.0 88.0 70.0 73.0 90.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:87.50 stddev:23.20 (26.5%) stddev-sans-first:24.30 08:58:10 INFO - PROCESS | 1682 | Values: 90.0 86.0 89.0 87.0 90.0 74.0 135.0 88.0 133.0 132.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:116.80 Median:95.00 stddev:26.54 (27.9%) stddev-sans-first:24.70 08:58:10 INFO - PROCESS | 1682 | Values: 153.0 96.0 131.0 153.0 94.0 97.0 96.0 98.0 150.0 100.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:105.30 Median:86.50 stddev:19.90 (23.0%) stddev-sans-first:17.23 08:58:10 INFO - PROCESS | 1682 | Values: 138.0 136.0 85.0 111.0 113.0 89.0 112.0 91.0 88.0 90.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:70.00 Median:71.50 stddev:13.00 (18.2%) stddev-sans-first:13.16 08:58:10 INFO - PROCESS | 1682 | Values: 59.0 65.0 78.0 86.0 91.0 83.0 59.0 59.0 59.0 61.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:79.50 stddev:12.03 (15.1%) stddev-sans-first:11.88 08:58:10 INFO - PROCESS | 1682 | Values: 70.0 77.0 80.0 80.0 79.0 70.0 93.0 104.0 99.0 73.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:97.30 Median:87.50 stddev:11.01 (12.6%) stddev-sans-first:11.59 08:58:10 INFO - PROCESS | 1682 | Values: 101.0 115.0 112.0 109.0 88.0 91.0 87.0 90.0 87.0 93.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:159.00 stddev:14.30 (9.0%) stddev-sans-first:15.07 08:58:10 INFO - PROCESS | 1682 | Values: 156.0 168.0 165.0 162.0 154.0 149.0 151.0 152.0 197.0 152.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:179.00 stddev:7.76 (4.3%) stddev-sans-first:8.13 08:58:10 INFO - PROCESS | 1682 | Values: 177.0 180.0 178.0 177.0 181.0 175.0 178.0 180.0 177.0 202.0 08:58:10 INFO - PROCESS | 1682 | 08:58:10 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:71.00 stddev:9.17 (12.9%) stddev-sans-first:9.55 08:58:10 INFO - PROCESS | 1682 | Values: 69.0 71.0 68.0 67.0 71.0 71.0 90.0 92.0 72.0 68.0 08:58:10 INFO - PROCESS | 1682 | -------- Summary: end -------- 08:58:10 INFO - PROCESS | 1682 | 08:58:11 INFO - TEST-INFO | 1682: exit 0 08:58:11 INFO - TEST-OK | kraken | took 61557ms 08:58:11 INFO - SUITE-END | took 1176s 08:58:11 INFO - Completed test suite (00:19:37) 08:58:11 INFO - javascript benchmark 08:58:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7084.350833842886, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.734501239901, 3342.916427089323, 3366.5455163552915, 3368.915777105572, 3381.4927760835885, 5598.076317407623, 5618.325800903887, 5623.100303951369, 5628.071368224243, 5702.600817942745, 5556.583256255777, 5639.971800141004, 5708.686022268774, 5735.397783232753, 5768.39431859655, 5107.131787596099, 5156.149235376159, 5204.739763011853, 5225.451327610604, 5259.842204733845, 5182.637215394924, 5276.15581506959, 5285.392179899303, 5308.654937429074, 5332.893342133155, 5131.7434128293435, 5167.612429067797, 5186.481351864821, 5198.2982297389935, 5252.395974462947, 5183.067047931371, 5196.92204616931, 5204.271402003723, 5209.114450543379, 5243.4232234454175, 5145.76844042019, 5170.069387510247, 5171.612129090333, 5219.138842091051, 5237.162054071368, 4696.530346965276, 5119.744012799345, 5571.749271282762, 5606.691631960241, 5654.491095801398, 6141.393954534099, 6180.690965451709, 6224.0, 6231.719572619243, 6238.9376106238815, 6270.87458250837, 6278.748850045993, 6288.685565721696, 6298.338674439164, 6310.274318453391, 5621.578381621394, 5667.093265077568, 5684.943150568524, 5797.971010144965, 5808.30300363959, 6216.129741836146, 6298.716557754912, 6309.905351419732, 6311.684415779193, 6329.651869147241, 4442.755648439367, 6070.423309785563, 6080.90878636812, 6088.208532890784, 6095.969520152459, 5603.887922241533, 6129.570930034854, 6183.907241391383, 6244.063390491463, 6254.499640028786, 4712.552307530779, 5420.4850539198715, 6091.42131497507, 6211.3167551569295, 6245.344238854946, 6166.845828854314, 6208.379162083846, 6212.72042758077, 6230.875382492325, 6279.277883043417, 4859.173940430135, 4896.3389942358035, 4963.875903102451, 4977.352944117241, 4987.825426110068, 4382.561743825592, 4383.298672212431, 4430.867073987785, 4452.4211852458975, 4495.4605447345875, 5655.80204692842, 5793.275840519935, 5874.882502349929, 5875.50058245054, 5886.764529418775, 5165.9225111623355, 6060.727267272983, 6194.730080333521, 6248.125262463259, 6257.74969001244, 5056.342675452242, 5647.068233741469, 5856.385079566669, 5880.235569375954, 5897.882042359129, 4742.1227072991605, 4797.280407938837, 5365.570754339644, 5390.407055223922, 5415.323084614423, 5353.678779273256, 6108.877822443526, 6358.427741503287, 6361.109444677749, 6367.617942923532, 6117.492485019964, 6173.351798061319, 6183.876322473436, 6192.473639740672, 6215.81352559424, 6299.937000630119, 6309.179806625109, 6337.65142917144, 6342.52431067681, 6373.3945275198785, 6044.607100538451, 6131.202943617302, 6178.382161783786, 6188.752449901954, 6211.627302361872, 5708.80019199326, 5958.076498142795, 6164.660943648142, 6218.751249950133, 6305.96847015762, 6100.847478813065, 6104.725287362168, 6109.877802443837, 6121.051237058263, 6126.448619624255, 5861.208736820475, 6106.6336019838955, 6115.6330620162935, 6122.357152499102, 6127.295361033538, 5228.424873263862, 5783.595148339576, 5814.651120932757, 5918.141869428822, 5978.730957106854, 5979.282486101695, 6021.277446706424, 6041.154238406714, 6046.0, 6050.21347224858, 5852.882942341044, 5856.912146317723, 5892.1161825726485, 5931.4068593142065, 5932.614380065282], "value": 5627.931732433843, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.9434867815863, 495.67285591509614, 514.6628958032488, 529.6160283794247, 544.244810486182, 31694.366112677762, 32128.0, 39714.99999999996, 39728.404073938844, 39847.00382490432, 39649.60350396495, 40023.0, 40069.0, 40072.998175045635, 40152.19695606086, 9179.90820091799, 9320.161185493303, 9388.436693798394, 9420.24638028956, 9420.481873496956, 9298.349115561914, 9400.05999400058, 9411.576479058458, 9422.057794220591, 9431.056894310583, 9371.95314023429, 9395.389299695498, 9396.248300135972, 9411.670591529299, 9422.858657120149, 9332.580033898457, 9368.812623747555, 9370.718878433658, 9375.906240937571, 9377.202937750299, 9020.097990200993, 9069.863952040725, 9072.8639070414, 9078.364514483988, 9124.726258212264, 38616.80691596552, 38768.418473722915, 38781.0, 38792.80603596992, 38883.61116388828, 2783.705576906728, 2784.4570308789794, 3082.9537556936666, 3234.7735658503916, 3279.2949515853966, 23852.0, 26594.734052659613, 29953.700462995526, 29980.250493737614, 30031.849840750874, 2980.249031790975, 3137.4352616529018, 3159.573457583243, 3165.8100513969007, 3250.8374581271078, 475054.0, 478573.0, 480206.0, 480688.59655701846, 482124.0, 60307.69846150829, 60589.69705151446, 60789.08816367669, 60916.69541652352, 60968.695156523936, 53748.73125634347, 54933.90132197334, 59299.4770575245, 61894.0, 62262.0, 15698.293576789072, 15840.970336928292, 15913.761293580606, 15963.920180399024, 15979.360825567079, 12085.637430877088, 12140.878591214152, 12200.755984880252, 12253.69365765863, 12270.570530031406, 14256.501022464165, 15894.046357218604, 16959.44836644222, 18062.729059064124, 18893.811061889206, 7757.7672669819995, 7778.455508114491, 7843.9215607844335, 7926.454341403486, 7976.680932762741, 812.6058861452191, 915.606289295596, 951.8143961927358, 974.6345120579782, 1002.1682003936716, 25345.366365841, 27404.45191096167, 28533.0, 29409.55885661717, 29862.0, 1000.0849222961054, 1007.8740157480315, 1041.3023274406166, 1074.6829685242872, 1094.7427354571669, 14544.236595046907, 15014.174220417983, 15445.0, 15462.9226853865, 15553.76669349938, 646730.0, 652331.0, 652889.0, 653280.0, 654598.0, 55071.72464137734, 55072.0, 55251.723741381036, 55446.0, 55494.722526387915, 1127.9661610151709, 1264.759695657822, 1328.7209685965863, 1335.859734727859, 1357.565579014706, 22977.195798146986, 24572.877135614206, 26865.0, 29126.68929898117, 29668.703312966594, 40376.0, 41945.79027104845, 49543.0, 50155.0, 50250.74874625603, 594157.0, 601538.0, 602317.0, 602608.7260837549, 603613.0, 25869.611955821056, 26178.08376706806, 26198.0, 26295.0, 26316.473670526866, 44026.559734402246, 44588.55411445844, 44703.10593788171, 45458.31812522881, 45924.540754592024, 37670.81164594269, 37745.81127094457, 38563.42154867623, 38572.421413678254, 39244.60755392409, 36903.26193476169, 36939.44590831086, 37806.43290350702, 38204.0, 38628.6137138625, 32299.51550726694, 32682.34635307233, 36742.26515469729, 36889.63110368862, 37143.44284835675, 31923.042308730775, 32772.180695482806, 33867.814626487954, 33925.64297428075, 33973.0, 24529.386765331008, 25241.621375679748, 25290.873545632156, 25424.363641811204, 25993.090241841444], "value": 19241.661839137563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.59072952199244, 363.38950563054067, 369.09755647442, 378.10012171032935, 389.4508742672832, 18210.817891821094, 18751.718724219132, 19256.422307330802, 19721.605567888633, 19919.800801991976, 16783.496495105137, 19513.0, 19805.80194198061, 20102.798972010274, 20308.289209877657, 5609.102543593026, 5760.847830433909, 5787.536997040248, 5890.381509941459, 5904.55715821313, 5118.872028199287, 5410.675359478424, 5581.469760372758, 5723.399043100477, 5756.251687280647, 4589.426321709786, 5699.2306038684865, 5714.71426428679, 5721.656700597957, 5736.913946290809, 5274.894502109974, 5619.690916999563, 5646.096624540054, 5755.9424405756035, 5760.366359700451, 4679.532046795327, 4947.826826061107, 5039.672421292605, 5046.646734728571, 5048.318477005594, 23235.651465228035, 23318.533629327318, 23887.522249555084, 24151.758482415127, 24326.756732432626, 2351.870647114408, 2514.911978080777, 2557.974420255792, 2614.3986883017014, 2799.908035866014, 9536.809263814754, 9753.756156096155, 9766.755831104208, 10267.127294180003, 10311.639092631796, 1752.7195648696274, 1754.1317048061292, 1756.0341812003476, 1767.805541390446, 1871.6631006418838, 102073.48963255211, 110384.34423483654, 110613.44693276483, 111710.21650778102, 112044.0, 9390.67132650354, 9927.453990030474, 9968.700938971842, 10027.548760305657, 10049.648262310784, 12069.939650301869, 12306.261624302391, 12368.1960672556, 12376.0, 12380.442880070419, 7697.384209263246, 7865.174156713462, 7907.802304942423, 7987.960060199662, 8019.198080192051, 8153.8776918346275, 8180.100188979208, 8181.550014749127, 8288.58557072144, 8352.413041522213, 3322.1030321812973, 3487.8081705505933, 3604.8824864292155, 3832.3485007548784, 3910.159315747071, 1672.3143511160365, 1886.2455017992913, 1947.9610407791765, 1954.7813231981804, 1978.3273686946507, 711.7615349292195, 834.5395557773895, 849.7450764770545, 934.5467448287575, 1011.4993078426226, 15862.04827710318, 17421.0, 19484.80515194858, 19583.804161958484, 20517.0, 788.2826627768703, 890.617034675096, 897.1611543207122, 936.3679516326453, 960.9039096090335, 8473.279771219391, 10088.293819432716, 10646.307989980627, 10657.520411581498, 10701.518431670593, 586323.0, 591363.0, 595151.0, 605514.0, 607716.0, 126742.36628816981, 128133.71866281219, 128162.35918820347, 130381.34809325893, 134809.0, 938.9812203755887, 947.190152419685, 968.7820240445843, 971.1114330387758, 975.453745902297, 18004.639907202043, 18050.27798888082, 18233.4529964096, 18240.270389184283, 18408.171632276313, 55289.1706624409, 56409.0, 57173.0, 57836.71081644733, 58139.70930145492, 64230.0, 81860.59069704614, 83022.58488707518, 83389.58305208434, 83949.0, 7801.648925798239, 7919.336939242824, 8016.118226995142, 8338.0, 8372.958135209285, 12265.080118990933, 12701.237925724487, 13013.21920684762, 13129.540466083641, 13389.794918457386, 11087.669479662492, 11098.389588572705, 11336.263142895685, 11408.372539510407, 11504.53981840717, 11169.218154729091, 11178.888211117785, 11241.60654377093, 11648.534058637559, 11786.351750653796, 10686.839697404701, 10750.08624266946, 11199.0, 11240.988311052044, 11742.765144697229, 6806.897896531655, 8132.3900707448365, 8187.672493100209, 8443.986721593448, 8564.143585641636, 10042.297039207186, 10931.125509959344, 11268.211225214156, 11291.266067705574, 11332.376719280519], "value": 9204.903327245087, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.1822452090048, 1460.4450308882626, 1643.852053315202, 1768.2396569475136, 1798.5863251452165, 7576.090869095702, 7825.726099586515, 8114.959425202873, 8603.483790972547, 8604.268637165833, 8042.758717238492, 8335.083140854516, 8400.369972252074, 8411.411201215917, 8461.661533538667, 2299.7700229977036, 2324.418895276181, 2330.07961855068, 2330.533893221354, 2338.1115176233006, 2297.0926484038796, 2352.9176478823247, 2353.211674089182, 2355.128602417108, 2360.079568968104, 2277.3168049585142, 2277.3737222263844, 2294.403455101677, 2314.259436980167, 2321.3732292281074, 2278.031836469502, 2282.0643536150264, 2302.4243939015246, 2305.101010605865, 2309.5842748305213, 2303.792658660712, 2320.9071637134493, 2330.4406942333885, 2334.8248881333966, 2335.322756400642, 8376.288015518689, 8444.028936672239, 8471.06818249992, 8504.914950850474, 8541.359398045171, 5096.108181068298, 5218.13900706383, 5250.44870288618, 5261.263423120766, 5295.4704529547125, 3237.546743455918, 3404.063882432351, 3542.8937131886087, 3614.7831130132276, 3617.0, 985.635314933472, 1018.3279035836313, 1039.7764480636624, 1103.773726386097, 1105.8064838653204, 10083.899161008443, 10431.5827366906, 10879.0, 11012.50443730034, 11052.834207486814, 4408.7575183365225, 4543.454785425704, 4596.954030459653, 4636.281376386665, 4661.673682842236, 9399.389039712396, 9420.434773913586, 9437.009114043063, 9446.952765236267, 9466.0, 5181.248718935733, 5206.739663016834, 5208.218767184877, 5217.556507696812, 5221.373435187726, 4311.913761724748, 4319.632831209383, 4732.432108147044, 4785.784639691222, 4794.95205047952, 3701.9629803702155, 3735.159589092487, 3796.468494410785, 3820.9617903820604, 3834.2331533693373, 1253.147859455579, 1260.697432616184, 1267.7211013576998, 1274.0381012335813, 1278.2586100061942, 651.7686221391433, 789.5499565247753, 850.0139837788136, 867.8611422172422, 872.7556284240422, 5640.887182256414, 5832.0, 5891.734871930774, 6057.061155520902, 6150.261968563712, 736.6574542837499, 891.7993451473367, 924.0851556958697, 960.8750862387844, 975.5463709375175, 3795.906841252582, 3837.2517359114704, 3881.3595756700392, 3935.7244992850797, 3969.1069509360163, 9168.908310916939, 9932.851007234753, 9950.805903291506, 10326.070653641058, 10386.636467723594, 9587.0, 10101.646442374482, 10350.637727679496, 10358.119559837498, 10370.0, 7661.61691915402, 7987.321077708344, 7988.640511177011, 7999.240072193133, 8006.639701213459, 5461.044317244387, 5628.212050313042, 5672.829815105553, 5717.971410142923, 5744.138379243147, 9437.858432123661, 9576.329656924227, 9588.328816982745, 9588.76028099303, 9602.135807777333, 8292.419530632798, 9233.984261731339, 9573.521323933635, 9714.75713107178, 9799.85300220483, 6737.9326206737305, 6768.830779230559, 6794.082798822095, 6833.282505336868, 6841.62371069596, 7780.066392032993, 7889.960550197212, 7892.210778922292, 7913.287804097658, 7923.326517246099, 7014.193367762773, 7023.719051238098, 7092.680829362795, 7286.817829554304, 7313.744018959524, 6980.581165130108, 7102.893456598052, 7134.072570565793, 7212.9803654976695, 7224.0, 6610.504212184202, 6639.800805975829, 6648.335166483506, 6685.966570167118, 6697.866042679217, 4803.903921921472, 4845.176320025675, 4867.58625516835, 4894.1679914415345, 4971.502849715, 4512.278035514302, 4784.569388755029, 4808.182608956556, 4863.878403039811, 4939.357883475125], "value": 4424.434400327172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.4823806576556, 302.73359443689554, 308.14336145515466, 310.51249538224994, 313.2872714574343, 4124.670026397888, 4563.338315944186, 5034.1190291698895, 5287.92068118978, 5523.972380138093, 4177.707560470761, 4526.619381088774, 4958.760309922511, 5037.899242015166, 5116.641835071547, 3423.965760342402, 3746.9437958430644, 4102.979485102585, 4112.568180341066, 4127.2570937231285, 3578.92842143157, 3703.8703645372416, 3782.9621703782886, 4131.586841315874, 4159.23054234966, 4045.5145382554133, 4064.471618689581, 4088.7342322749073, 4112.2186784511, 4119.711620186573, 3464.6535346465403, 3986.3223252047223, 4044.635982761551, 4066.1054567995143, 4072.348424252135, 3422.408580010307, 3566.251087271676, 3572.7677700949357, 3592.892213233581, 3627.963720362789, 4754.904901901978, 5097.5667068299235, 5168.8449346519665, 5220.660657057279, 5243.737813109329, 3886.319894018564, 4585.358049873021, 4591.977040114778, 4597.172508948388, 4606.355110284563, 5223.869403264911, 5556.86107847303, 5600.243967064435, 5610.775568977236, 5621.718914054321, 3580.337637537064, 3807.2956503047026, 3809.9619003809885, 3829.5213098362706, 3841.42378643203, 5121.539061484485, 5736.397678243766, 5764.423557644286, 5783.2192653992015, 5803.245578074865, 2964.3330250693853, 3373.5445714828675, 3722.906927326784, 3733.5893051764283, 3856.1902000580194, 3139.7645176611845, 3780.31954248238, 3888.572257051722, 3932.9213415731524, 3954.9604503954592, 3909.7263191576885, 3980.0, 4518.186726389282, 4528.954710452919, 4539.182947069493, 5574.386817450078, 5592.86017849557, 5610.7194640268635, 5610.97194514033, 5632.381447213328, 1121.3440137519478, 1145.8255288329528, 1239.609523000252, 1269.416068608432, 1274.2099898063261, 783.9804004899809, 813.3493205435627, 823.8393513264856, 844.742353582151, 847.6100993543039, 4273.594008569181, 4565.817367305337, 4860.826750027445, 5163.741812909339, 5171.689698618095, 4738.763061846894, 4754.405699287589, 5357.303550538483, 5389.8113566025, 5559.444055594408, 4479.686421950498, 5063.999720110536, 5109.014968324713, 5142.614303927146, 5149.76826042829, 2883.423315336941, 2892.840893750864, 2896.9275768105545, 2909.941801163965, 2922.6638936522145, 5136.5377116059735, 5640.7743690252755, 5774.162746401748, 5784.88430231393, 6149.877002459926, 6108.480779133734, 6119.847003824851, 6158.753649854045, 6195.287541932646, 6239.625622462667, 4888.804447822047, 6147.293061297919, 6181.8454538636, 6217.0, 6217.378262173746, 5097.980403919157, 5394.0290747665795, 5691.345495268097, 5873.530117590497, 5947.226860508106, 5238.590248485587, 5545.833624991256, 5747.712614369181, 5802.593818432669, 5827.0, 5382.865428364165, 5451.088937264255, 6036.9698151508965, 6109.725062372293, 6139.72371243304, 3552.3783337915243, 4018.65841403484, 4039.3335099709393, 4040.575739547305, 4071.633552980246, 5114.181730923035, 5349.545288650508, 5842.970785146047, 5848.970755146197, 5877.03300272323, 4586.743412177391, 5288.629795914249, 5461.787975869779, 5670.829875103754, 5721.599488035798, 4496.595306422438, 4556.65825063115, 4863.90272194547, 5709.48698594881, 5712.5721568332465, 4694.976525117352, 4785.401824771903, 5267.312092237835, 5375.333646569576, 5590.183190463034, 5656.038473459604, 5669.829905102853, 5679.971600141973, 5698.572607054571, 5720.427957204246, 4918.6065114791445, 5012.273220383097, 5203.505666961632, 5262.6843289177705, 5328.200769884394], "value": 4035.273240702244, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.9383508566688, 657.8454063295122, 680.7209044291841, 688.6006116452459, 745.8769303064993, 13984.37070331835, 14803.629909252275, 14804.925975370108, 14844.77732834007, 14846.035007724504, 13143.342832858367, 13192.208467491957, 13334.266615336153, 13337.266450345227, 13357.465701371957, 776.8523980443712, 799.6521513141773, 802.2579114319232, 807.9353651707879, 809.0372456776417, 805.6334367862638, 808.9555074470902, 809.1423091522975, 811.6428771340621, 812.2202685422002, 835.4653022065883, 846.7967687754956, 848.5629900601195, 850.587465079436, 851.1190917400461, 827.9213474719892, 832.5753865528594, 845.20550682359, 846.090452763282, 846.9534175620338, 624.0857144283601, 628.3810446709987, 628.4909223528956, 630.5586089737202, 631.119588174494, 13864.445422183202, 14093.506727264497, 14233.78649320261, 14450.277486125651, 14455.13269203851, 12303.0, 13199.208047517084, 13253.13854599448, 13320.333983300796, 13679.460931443675, 10526.578936842518, 11298.548058077668, 11343.0, 11511.59709410175, 11641.6507504775, 3527.5414196154593, 3565.108722819295, 3702.426123950765, 3725.515682961198, 3737.4767532545466, 86168.0, 86321.0, 87764.56117719371, 90995.0, 91647.0, 10205.285630005978, 10226.897731022744, 10273.280870339155, 10341.534630941625, 10633.30883492586, 19736.210551577777, 19983.900080499505, 20015.699764503544, 20029.399118026482, 20238.88686122248, 7458.627068646546, 7489.775306740807, 7528.069114729751, 7600.9619951900995, 7638.809029774189, 4183.372494125844, 4212.684048696298, 4277.914441711148, 4403.581659742384, 4430.269005614037, 2904.8547572621283, 3242.8054316741072, 3273.8199399032806, 3320.070380293522, 3328.334333133383, 609.1745684597396, 617.1206031405229, 626.5301024231826, 629.4146443807304, 638.4573112854036, 520.9713465759344, 541.3666010767412, 569.6496654557417, 582.6445868020504, 601.9849503762354, 8497.0, 8631.352648551383, 8809.823803523894, 8838.248748856418, 8992.370534062684, 575.7754475754459, 586.5366360575183, 614.0911451052467, 616.7717944360616, 631.7522892287695, 3467.826608669556, 3471.5660542432197, 4052.2908491014546, 4160.604742549453, 4811.879703007383, 80395.0, 115311.42344288225, 119345.0, 119485.0, 125043.0, 59989.70005150034, 61771.0, 70365.0, 83684.58157709173, 104217.43673844899, 73300.63349683218, 73994.0, 77344.0, 86181.0, 86856.56571716974, 9069.0, 9538.904610953801, 9564.234861210947, 9597.28020398459, 9774.75563110928, 41717.165656687306, 51196.74401627968, 51533.0, 51802.0, 52964.73517632387, 70223.0, 72387.0, 75133.0, 76258.0, 77082.2291777075, 12125.0, 12238.265704057785, 12292.38538073075, 12689.36553172356, 12862.871371286168, 13114.770484020015, 13884.930575347058, 14021.3690383935, 14247.928760356132, 14475.27623618836, 12904.677383065124, 13018.609441716764, 13150.868491314965, 13244.735105297646, 13767.31163441844, 10180.440075795903, 13090.345482725636, 13189.802152967904, 13374.665633359244, 13703.383347749575, 10889.536186421816, 12442.626721198378, 12657.367131643565, 13177.868221317663, 13253.734925301633, 10530.631427899987, 10607.681769546925, 10674.46627668629, 11008.39453830015, 11343.716407089889, 10981.231313807957, 11165.715942666697, 11251.887481125412, 11369.54521819118, 11403.258788178742], "value": 7102.528883868639, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 114.0, 114.0, 146.0, 112.0, 130.0, 116.0, 117.0, 141.0, 115.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 238.0, 230.0, 196.0, 270.0, 241.0, 219.0, 216.0, 258.0], "value": 225.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 68.0, 111.0, 86.0, 72.0, 62.0, 59.0, 81.0, 82.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 90.0, 74.0, 71.0, 72.0, 88.0, 70.0, 73.0, 90.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 87.0, 90.0, 74.0, 135.0, 88.0, 133.0, 132.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 96.0, 131.0, 153.0, 94.0, 97.0, 96.0, 98.0, 150.0, 100.0], "value": 116.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 85.0, 111.0, 113.0, 89.0, 112.0, 91.0, 88.0, 90.0], "value": 105.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 78.0, 86.0, 91.0, 83.0, 59.0, 59.0, 59.0, 61.0], "value": 70.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 80.0, 80.0, 79.0, 70.0, 93.0, 104.0, 99.0, 73.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 112.0, 109.0, 88.0, 91.0, 87.0, 90.0, 87.0, 93.0], "value": 97.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 168.0, 165.0, 162.0, 154.0, 149.0, 151.0, 152.0, 197.0, 152.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 178.0, 177.0, 181.0, 175.0, 178.0, 180.0, 177.0, 202.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 68.0, 67.0, 71.0, 71.0, 90.0, 92.0, 72.0, 68.0], "value": 73.9, "unit": "score"}]}]} 08:58:11 INFO - Return code: 0 08:58:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:58:11 ERROR - # TBPL SUCCESS # 08:58:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:58:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:58:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:58:11 INFO - 2016-11-08 08:58:11.699 screenresolution[1687:10849] starting screenresolution argv=screenresolution get 08:58:11 INFO - 2016-11-08 08:58:11.701 screenresolution[1687:10849] Display 0: 1600x1200x32@60 08:58:11 INFO - 2016-11-08 08:58:11.706 screenresolution[1688:10852] starting screenresolution argv=screenresolution list 08:58:11 INFO - Available Modes on Display 0 08:58:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:58:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:58:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:58:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:58:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:58:11 INFO - Intel Iris: 08:58:11 INFO - Chipset Model: Intel Iris 08:58:11 INFO - Type: GPU 08:58:11 INFO - Bus: Built-In 08:58:11 INFO - VRAM (Dynamic, Max): 1536 MB 08:58:11 INFO - Vendor: Intel (0x8086) 08:58:11 INFO - Device ID: 0x0a2e 08:58:11 INFO - Revision ID: 0x0009 08:58:11 INFO - Displays: 08:58:11 INFO - Display: 08:58:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:58:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:58:11 INFO - Display Serial Number: 1600x1200 60 08:58:11 INFO - Main Display: Yes 08:58:11 INFO - Mirror: Off 08:58:11 INFO - Online: Yes 08:58:11 INFO - Rotation: Supported 08:58:11 INFO - Return code: 0 08:58:11 INFO - Running post-action listener: _resource_record_post_action 08:58:11 INFO - [mozharness: 2016-11-08 16:58:11.930444Z] Finished run-tests step (success) 08:58:11 INFO - Running post-run listener: _resource_record_post_run 08:58:12 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38504960; Write bytes: 708171264; Read time: 247; Write time: 2008 08:58:12 INFO - TinderboxPrint: CPU usage
54.4% 08:58:12 INFO - TinderboxPrint: I/O read bytes / time
38,504,960 / 247 08:58:12 INFO - TinderboxPrint: I/O write bytes / time
708,171,264 / 2,008 08:58:12 INFO - TinderboxPrint: CPU idle
2,211.6 (45.6%) 08:58:12 INFO - TinderboxPrint: CPU system
976.5 (20.1%) 08:58:12 INFO - TinderboxPrint: CPU user
1,661.6 (34.3%) 08:58:12 INFO - TinderboxPrint: Swap in / out
441,843,712 / 0 08:58:12 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 384213504; Write bytes: 378238464; Read time: 28908; Write time: 787 08:58:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37550592; Write bytes: 302256128; Read time: 235; Write time: 1159 08:58:12 INFO - Running post-run listener: _upload_blobber_files 08:58:12 INFO - Blob upload gear active. 08:58:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:58:12 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 08:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:58:12 INFO - (blobuploader) - INFO - Open directory for files ... 08:58:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:58:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:14 INFO - (blobuploader) - INFO - Done attempting. 08:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:14 INFO - (blobuploader) - INFO - Done attempting. 08:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:58:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:17 INFO - (blobuploader) - INFO - Done attempting. 08:58:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:58:17 INFO - Return code: 0 08:58:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:58:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:58:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8f68dd428a8dd6040fd224538f1138e66b4327d846833108fa05a381370c40b0d28ff5be43a215788ede4fbdccb3b29d1414674aceeb8aaf4a6a51feb5d06e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012f9862648bdee90d043e9de1e5e96f06fcdf1af85a21b0ccbeafacbfd7fc476f13c56cd54fd2b495a25d183f987f4d4444952836d9de065be979e12300d088", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e919de4e3299041ff27fc00a9e08d7caf2692053944333dfffed2726cf0e0bebc6f6145b5ce4232d3522f27bb4f78c3b7c1de91047f6627b038b495f9b94e4"} 08:58:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:58:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:58:17 INFO - Contents: 08:58:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8f68dd428a8dd6040fd224538f1138e66b4327d846833108fa05a381370c40b0d28ff5be43a215788ede4fbdccb3b29d1414674aceeb8aaf4a6a51feb5d06e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012f9862648bdee90d043e9de1e5e96f06fcdf1af85a21b0ccbeafacbfd7fc476f13c56cd54fd2b495a25d183f987f4d4444952836d9de065be979e12300d088", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e919de4e3299041ff27fc00a9e08d7caf2692053944333dfffed2726cf0e0bebc6f6145b5ce4232d3522f27bb4f78c3b7c1de91047f6627b038b495f9b94e4"} 08:58:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:58:17 INFO - Copying logs to upload dir... 08:58:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:58:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.890396 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-08 08:58:17.596027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:17.600238) ========= 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.qeeHlKZZ9r/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.jYdWaH3f82/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8f68dd428a8dd6040fd224538f1138e66b4327d846833108fa05a381370c40b0d28ff5be43a215788ede4fbdccb3b29d1414674aceeb8aaf4a6a51feb5d06e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012f9862648bdee90d043e9de1e5e96f06fcdf1af85a21b0ccbeafacbfd7fc476f13c56cd54fd2b495a25d183f987f4d4444952836d9de065be979e12300d088", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e919de4e3299041ff27fc00a9e08d7caf2692053944333dfffed2726cf0e0bebc6f6145b5ce4232d3522f27bb4f78c3b7c1de91047f6627b038b495f9b94e4"} build_url:https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a8f68dd428a8dd6040fd224538f1138e66b4327d846833108fa05a381370c40b0d28ff5be43a215788ede4fbdccb3b29d1414674aceeb8aaf4a6a51feb5d06e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012f9862648bdee90d043e9de1e5e96f06fcdf1af85a21b0ccbeafacbfd7fc476f13c56cd54fd2b495a25d183f987f4d4444952836d9de065be979e12300d088", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e919de4e3299041ff27fc00a9e08d7caf2692053944333dfffed2726cf0e0bebc6f6145b5ce4232d3522f27bb4f78c3b7c1de91047f6627b038b495f9b94e4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:17.639974) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:17.640351) ========= 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.qeeHlKZZ9r/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.jYdWaH3f82/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.005059 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:17.691072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 08:58:17.691444) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 08:58:17.691832) ========= ========= Total master_lag: 0.20 =========