builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1484435484.92 results: success (0) buildid: 20170114140724 builduid: f90f2fa9824a407b8db1fe35f58261eb revision: 1e04cc4582dd86864035f0aed5fac81907dea848 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.920908) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.921325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.921607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.947642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:24.947923) ========= 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.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-14 15:11:24-- 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% 171M=0s 2017-01-14 15:11:25 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.788864 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.760340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.760640) ========= 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.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.027056 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:25.808459) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 15:11:25.808765) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1e04cc4582dd86864035f0aed5fac81907dea848 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1e04cc4582dd86864035f0aed5fac81907dea848 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-14 15:11:25,908 truncating revision to first 12 chars 2017-01-14 15:11:25,908 Setting DEBUG logging. 2017-01-14 15:11:25,908 attempt 1/10 2017-01-14 15:11:25,908 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1e04cc4582dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-14 15:11:26,614 unpacking tar archive at: mozilla-aurora-1e04cc4582dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.983755 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 15:11:26.816526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:26.816788) ========= script_repo_revision: 1e04cc4582dd86864035f0aed5fac81907dea848 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:26.817176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:26.817442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 15:11:26.838249) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-14 15:11:26.838504) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:11:27 INFO - MultiFileLogger online at 20170114 15:11:27 in /builds/slave/test 15:11:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:11:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:11:27 INFO - {'append_to_log': False, 15:11:27 INFO - 'base_work_dir': '/builds/slave/test', 15:11:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:11:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:11:27 INFO - 'branch': 'Mozilla-Aurora', 15:11:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:11:27 INFO - 'config_files': ('talos/mac_config.py',), 15:11:27 INFO - 'default_actions': ('clobber', 15:11:27 INFO - 'read-buildbot-config', 15:11:27 INFO - 'download-and-extract', 15:11:27 INFO - 'populate-webroot', 15:11:27 INFO - 'create-virtualenv', 15:11:27 INFO - 'install', 15:11:27 INFO - 'run-tests'), 15:11:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:11:27 INFO - 'download_minidump_stackwalk': True, 15:11:27 INFO - 'download_symbols': 'ondemand', 15:11:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:11:27 INFO - 'tooltool.py': '/tools/tooltool.py', 15:11:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:11:27 INFO - '/tools/misc-python/virtualenv.py')}, 15:11:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:11:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:11:27 INFO - 'installer_path': 'installer.exe', 15:11:27 INFO - 'log_level': 'info', 15:11:27 INFO - 'log_name': 'talos', 15:11:27 INFO - 'log_to_console': True, 15:11:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:11:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:11:27 INFO - 'opt_config_files': (), 15:11:27 INFO - 'pip_index': False, 15:11:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:27 INFO - 'cmd': ('bash', 15:11:27 INFO - '-c', 15:11:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:27 INFO - 'enabled': True, 15:11:27 INFO - 'halt_on_failure': False, 15:11:27 INFO - 'name': 'check_screen_resolution'},), 15:11:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:27 INFO - 'cmd': ('bash', 15:11:27 INFO - '-c', 15:11:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:27 INFO - 'enabled': True, 15:11:27 INFO - 'halt_on_failure': False, 15:11:27 INFO - 'name': 'check_screen_resolution'},), 15:11:27 INFO - 'run_cmd_checks_enabled': True, 15:11:27 INFO - 'sps_profile': False, 15:11:27 INFO - 'sps_profile_interval': 0, 15:11:27 INFO - 'suite': 'dromaeojs-e10s', 15:11:27 INFO - 'system_bits': '32', 15:11:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:11:27 INFO - 'title': 't-yosemite-r7-0004', 15:11:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:11:27 INFO - 'use_talos_json': True, 15:11:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:11:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:11:27 INFO - 'work_dir': 'build'} 15:11:27 INFO - [mozharness: 2017-01-14 23:11:27.044320Z] Running clobber step. 15:11:27 INFO - Running pre-action listener: _resource_record_pre_action 15:11:27 INFO - Running main action method: clobber 15:11:27 INFO - rmtree: /builds/slave/test/build 15:11:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:11:29 INFO - Running post-action listener: _resource_record_post_action 15:11:29 INFO - [mozharness: 2017-01-14 23:11:29.444797Z] Finished clobber step (success) 15:11:29 INFO - [mozharness: 2017-01-14 23:11:29.444914Z] Running read-buildbot-config step. 15:11:29 INFO - Running pre-action listener: _resource_record_pre_action 15:11:29 INFO - Running main action method: read_buildbot_config 15:11:29 INFO - Using buildbot properties: 15:11:29 INFO - { 15:11:29 INFO - "project": "", 15:11:29 INFO - "product": "firefox", 15:11:29 INFO - "script_repo_revision": "production", 15:11:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:11:29 INFO - "repository": "", 15:11:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:11:29 INFO - "buildid": "20170114140724", 15:11:29 INFO - "pgo_build": "False", 15:11:29 INFO - "basedir": "/builds/slave/test", 15:11:29 INFO - "buildnumber": 69, 15:11:29 INFO - "slavename": "t-yosemite-r7-0004", 15:11:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:11:29 INFO - "platform": "yosemite_r7", 15:11:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:11:29 INFO - "branch": "mozilla-aurora", 15:11:29 INFO - "repo_path": "releases/mozilla-aurora", 15:11:29 INFO - "revision": "1e04cc4582dd86864035f0aed5fac81907dea848", 15:11:29 INFO - "stage_platform": "macosx64", 15:11:29 INFO - "builduid": "f90f2fa9824a407b8db1fe35f58261eb", 15:11:29 INFO - "slavebuilddir": "test" 15:11:29 INFO - } 15:11:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:11:29 INFO - Running post-action listener: _resource_record_post_action 15:11:29 INFO - [mozharness: 2017-01-14 23:11:29.448298Z] Finished read-buildbot-config step (success) 15:11:29 INFO - [mozharness: 2017-01-14 23:11:29.448414Z] Running download-and-extract step. 15:11:29 INFO - Running pre-action listener: _resource_record_pre_action 15:11:29 INFO - Running main action method: download_and_extract 15:11:29 INFO - mkdir: /builds/slave/test/build/tests 15:11:29 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')]} 15:11:29 INFO - trying https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:11:30 INFO - Downloaded 1199 bytes. 15:11:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:30 INFO - Using the following test package requirements: 15:11:30 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:30 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:11:30 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:11:30 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'jsshell-mac.zip'], 15:11:30 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:11:30 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:30 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:11:30 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:11:30 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:11:30 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:30 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:11:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:11:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:31 INFO - Http code: 200 15:11:31 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 15:11:31 INFO - x-amz-cf-id: QyKPRMhveSkMtIHFgjwvXWxR4aGPRPt29lDjY8qzvkh_8maTGaThcQ== 15:11:31 INFO - x-amz-version-id: qIinis5NuhIPH_MYtY1bGhHpdm.rtZ0L 15:11:31 INFO - Expected file size: 16275666 15:11:31 INFO - Obtained file size: 16275666 15:11:37 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:11:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:38 INFO - Http code: 200 15:11:38 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 15:11:38 INFO - x-amz-cf-id: e6KM5rg665on8_rnXB2n-xkRtLC0PGpSVULpACwMpvA-O5VmBpn_wQ== 15:11:38 INFO - x-amz-version-id: qIinis5NuhIPH_MYtY1bGhHpdm.rtZ0L 15:11:38 INFO - Expected file size: 16275666 15:11:38 INFO - Obtained file size: 16275666 15:11:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:11:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:11:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:11:45 INFO - Http code: 200 15:11:45 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 15:11:45 INFO - x-amz-cf-id: nRHnEIIyK2bQjgVxfwKIAXvaXrkfwZHA56fTd0ZJvxXQb0ie3jk1qA== 15:11:45 INFO - x-amz-version-id: XhYLRgJFHXgzH1IGf4nOk7CBNGt4b.ka 15:11:45 INFO - Expected file size: 14409038 15:11:45 INFO - Obtained file size: 14409038 15:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:46 INFO - trying https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:11:49 INFO - Downloaded 109991904 bytes. 15:11:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:49 INFO - mkdir: /builds/slave/test/properties 15:11:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:49 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:49 INFO - Contents: 15:11:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:50 INFO - Running post-action listener: _resource_record_post_action 15:11:50 INFO - Running post-action listener: set_extra_try_arguments 15:11:50 INFO - [mozharness: 2017-01-14 23:11:50.034960Z] Finished download-and-extract step (success) 15:11:50 INFO - [mozharness: 2017-01-14 23:11:50.035065Z] Running populate-webroot step. 15:11:50 INFO - Running pre-action listener: _resource_record_pre_action 15:11:50 INFO - Running main action method: populate_webroot 15:11:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:11:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:11:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:11:50 INFO - '--tppagecycles', 15:11:50 INFO - '7'], 15:11:50 INFO - 'tests': ['tsvgm']}}, 15:11:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:11:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:11:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['damp', 'tps']}, 15:11:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'tests': ['damp', 'tps']}, 15:11:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['dromaeo_dom']}, 15:11:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['basic_compositor_video']}, 15:11:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:11:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['a11yr', 15:11:50 INFO - 'ts_paint', 15:11:50 INFO - 'tpaint', 15:11:50 INFO - 'sessionrestore', 15:11:50 INFO - 'sessionrestore_no_auto_restore', 15:11:50 INFO - 'tabpaint']}, 15:11:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['tsvgx', 15:11:50 INFO - 'tsvgr_opacity', 15:11:50 INFO - 'tart', 15:11:50 INFO - 'tscrollx', 15:11:50 INFO - 'cart']}, 15:11:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:50 INFO - 'tsvgr_opacity', 15:11:50 INFO - 'tart', 15:11:50 INFO - 'tscrollx', 15:11:50 INFO - 'cart']}, 15:11:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'talos_options': ['--disable-e10s'], 15:11:50 INFO - 'tests': ['tp5o']}, 15:11:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'tests': ['tp5o']}, 15:11:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'talos_options': ['--disable-e10s', 15:11:50 INFO - '--xperf_path', 15:11:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:50 INFO - 'tests': ['tp5n']}, 15:11:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:50 INFO - 'talos_options': ['--xperf_path', 15:11:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:50 INFO - 'tests': ['tp5n']}}, 15:11:50 INFO - 'talos.zip': {'path': '', 15:11:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:11:50 INFO - Running post-action listener: _resource_record_post_action 15:11:50 INFO - [mozharness: 2017-01-14 23:11:50.054305Z] Finished populate-webroot step (success) 15:11:50 INFO - [mozharness: 2017-01-14 23:11:50.054433Z] Running create-virtualenv step. 15:11:50 INFO - Running pre-action listener: _resource_record_pre_action 15:11:50 INFO - Running main action method: create_virtualenv 15:11:50 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:50 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 15:11:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:11:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:50 INFO - Using real prefix '/tools/python27' 15:11:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:11:53 INFO - Installing pip.................done. 15:11:53 INFO - Return code: 0 15:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:11:54 INFO - Reading from file tmpfile_stdout 15:11:54 INFO - Output received: 15:11:54 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) 15:11:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:54 INFO - 'HOME': '/Users/cltbld', 15:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:54 INFO - 'LOGNAME': 'cltbld', 15:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:54 INFO - 'MOZ_NO_REMOTE': '1', 15:11:54 INFO - 'NO_EM_RESTART': '1', 15:11:54 INFO - 'PAGER': '/bin/cat', 15:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:54 INFO - 'PWD': '/builds/slave/test', 15:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:54 INFO - 'SHELL': '/bin/bash', 15:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:11:54 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:54 INFO - 'USER': 'cltbld', 15:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:54 INFO - 'XPC_FLAGS': '0x0', 15:11:54 INFO - 'XPC_SERVICE_NAME': '0', 15:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:54 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 15:11:54 INFO - Cleaning up... 15:11:54 INFO - Return code: 0 15:11:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:54 INFO - 'HOME': '/Users/cltbld', 15:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:54 INFO - 'LOGNAME': 'cltbld', 15:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:54 INFO - 'MOZ_NO_REMOTE': '1', 15:11:54 INFO - 'NO_EM_RESTART': '1', 15:11:54 INFO - 'PAGER': '/bin/cat', 15:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:54 INFO - 'PWD': '/builds/slave/test', 15:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:54 INFO - 'SHELL': '/bin/bash', 15:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:11:54 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:54 INFO - 'USER': 'cltbld', 15:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:54 INFO - 'XPC_FLAGS': '0x0', 15:11:54 INFO - 'XPC_SERVICE_NAME': '0', 15:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:54 INFO - Downloading/unpacking psutil>=3.1.1 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:11:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:58 INFO - Installing collected packages: psutil 15:11:58 INFO - Running setup.py install for psutil 15:11:58 INFO - building 'psutil._psutil_osx' extension 15:11:58 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 15:11:59 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 15:11:59 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 15:11:59 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 15:11:59 INFO - building 'psutil._psutil_posix' extension 15:11:59 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 15:11:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:11:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:11:59 INFO - ^ 15:11:59 INFO - 1 warning generated. 15:11:59 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 15:11:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:00 INFO - Successfully installed psutil 15:12:00 INFO - Cleaning up... 15:12:00 INFO - Return code: 0 15:12:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:12:00 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')]} 15:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:00 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')]} 15:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:00 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:00 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 15:12:00 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 15:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:00 INFO - 'HOME': '/Users/cltbld', 15:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:00 INFO - 'LOGNAME': 'cltbld', 15:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:00 INFO - 'MOZ_NO_REMOTE': '1', 15:12:00 INFO - 'NO_EM_RESTART': '1', 15:12:00 INFO - 'PAGER': '/bin/cat', 15:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:00 INFO - 'PWD': '/builds/slave/test', 15:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:00 INFO - 'SHELL': '/bin/bash', 15:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:00 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:00 INFO - 'USER': 'cltbld', 15:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:00 INFO - 'XPC_FLAGS': '0x0', 15:12:00 INFO - 'XPC_SERVICE_NAME': '0', 15:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:12:00 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 15:12:00 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 15:12:00 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 15:12:00 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 15:12:00 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 15:12:00 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 15:12:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:12:03 INFO - Installing collected packages: mozsystemmonitor 15:12:03 INFO - Running setup.py install for mozsystemmonitor 15:12:03 INFO - Successfully installed mozsystemmonitor 15:12:03 INFO - Cleaning up... 15:12:03 INFO - Return code: 0 15:12:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:03 INFO - 'HOME': '/Users/cltbld', 15:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:03 INFO - 'LOGNAME': 'cltbld', 15:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:03 INFO - 'MOZ_NO_REMOTE': '1', 15:12:03 INFO - 'NO_EM_RESTART': '1', 15:12:03 INFO - 'PAGER': '/bin/cat', 15:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:03 INFO - 'PWD': '/builds/slave/test', 15:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:03 INFO - 'SHELL': '/bin/bash', 15:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:03 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:03 INFO - 'USER': 'cltbld', 15:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:03 INFO - 'XPC_FLAGS': '0x0', 15:12:03 INFO - 'XPC_SERVICE_NAME': '0', 15:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:06 INFO - warning: no files found matching '*.txt' 15:12:06 INFO - no previously-included directories found matching 'build' 15:12:06 INFO - no previously-included directories found matching 'dist' 15:12:06 INFO - no previously-included directories found matching '.git*' 15:12:06 INFO - Installing collected packages: jsonschema, functools32 15:12:06 INFO - Running setup.py install for functools32 15:12:06 INFO - warning: no files found matching '*.txt' 15:12:06 INFO - no previously-included directories found matching 'build' 15:12:06 INFO - no previously-included directories found matching 'dist' 15:12:06 INFO - no previously-included directories found matching '.git*' 15:12:06 INFO - Successfully installed jsonschema functools32 15:12:06 INFO - Cleaning up... 15:12:07 INFO - Return code: 0 15:12:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:12:07 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')]} 15:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:07 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')]} 15:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:07 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:07 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 15:12:07 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 15:12:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:07 INFO - 'HOME': '/Users/cltbld', 15:12:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:07 INFO - 'LOGNAME': 'cltbld', 15:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:07 INFO - 'MOZ_NO_REMOTE': '1', 15:12:07 INFO - 'NO_EM_RESTART': '1', 15:12:07 INFO - 'PAGER': '/bin/cat', 15:12:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:07 INFO - 'PWD': '/builds/slave/test', 15:12:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:07 INFO - 'SHELL': '/bin/bash', 15:12:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:07 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:07 INFO - 'USER': 'cltbld', 15:12:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:07 INFO - 'XPC_FLAGS': '0x0', 15:12:07 INFO - 'XPC_SERVICE_NAME': '0', 15:12:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:12:07 INFO - Cleaning up... 15:12:07 INFO - Return code: 0 15:12:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:12:07 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')]} 15:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:07 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')]} 15:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:07 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:07 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 15:12:07 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 15:12:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:07 INFO - 'HOME': '/Users/cltbld', 15:12:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:07 INFO - 'LOGNAME': 'cltbld', 15:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:07 INFO - 'MOZ_NO_REMOTE': '1', 15:12:07 INFO - 'NO_EM_RESTART': '1', 15:12:07 INFO - 'PAGER': '/bin/cat', 15:12:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:07 INFO - 'PWD': '/builds/slave/test', 15:12:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:07 INFO - 'SHELL': '/bin/bash', 15:12:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:07 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:07 INFO - 'USER': 'cltbld', 15:12:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:07 INFO - 'XPC_FLAGS': '0x0', 15:12:07 INFO - 'XPC_SERVICE_NAME': '0', 15:12:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:12:07 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 15:12:07 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 15:12:07 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 15:12:07 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 15:12:07 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 15:12:07 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 15:12:10 INFO - Downloading blobuploader-1.2.4.tar.gz 15:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:12:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:12:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:10 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 15:12:11 INFO - Downloading docopt-0.6.1.tar.gz 15:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:12:11 INFO - Installing collected packages: blobuploader, requests, docopt 15:12:11 INFO - Running setup.py install for blobuploader 15:12:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:12:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:12:11 INFO - Running setup.py install for requests 15:12:12 INFO - Running setup.py install for docopt 15:12:12 INFO - Successfully installed blobuploader requests docopt 15:12:12 INFO - Cleaning up... 15:12:12 INFO - Return code: 0 15:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:12 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:12 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 15:12:12 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 15:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:12 INFO - 'HOME': '/Users/cltbld', 15:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:12 INFO - 'LOGNAME': 'cltbld', 15:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:12 INFO - 'MOZ_NO_REMOTE': '1', 15:12:12 INFO - 'NO_EM_RESTART': '1', 15:12:12 INFO - 'PAGER': '/bin/cat', 15:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:12 INFO - 'PWD': '/builds/slave/test', 15:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:12 INFO - 'SHELL': '/bin/bash', 15:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:12 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:12 INFO - 'USER': 'cltbld', 15:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:12 INFO - 'XPC_FLAGS': '0x0', 15:12:12 INFO - 'XPC_SERVICE_NAME': '0', 15:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:12 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ril3tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:12 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-wI5N2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:12 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-xSSIXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-mLlFuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-tPA2VB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-UbiOqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-upJCoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Ns2NRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-CTePzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ojNoll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ghTd7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:13 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-fb8Fa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:14 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-6WGWAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:14 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-QBmRl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:14 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-6Nb14n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:14 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-HhzCR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:14 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-gU0gMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:12:14 INFO - Running setup.py install for manifestparser 15:12:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:12:14 INFO - Running setup.py install for mozcrash 15:12:14 INFO - Running setup.py install for mozdebug 15:12:14 INFO - Running setup.py install for mozdevice 15:12:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Running setup.py install for mozfile 15:12:15 INFO - Running setup.py install for mozhttpd 15:12:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Running setup.py install for mozinfo 15:12:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Running setup.py install for mozInstall 15:12:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:12:15 INFO - Running setup.py install for mozleak 15:12:15 INFO - Running setup.py install for mozlog 15:12:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Running setup.py install for moznetwork 15:12:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Running setup.py install for mozprocess 15:12:16 INFO - Running setup.py install for mozprofile 15:12:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Running setup.py install for mozrunner 15:12:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:12:16 INFO - Running setup.py install for mozscreenshot 15:12:16 INFO - Running setup.py install for moztest 15:12:16 INFO - Running setup.py install for mozversion 15:12:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:12:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:12:17 INFO - Cleaning up... 15:12:17 INFO - Return code: 0 15:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:17 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')]} 15:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:17 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')]} 15:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:17 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:17 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 15:12:17 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 15:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:17 INFO - 'HOME': '/Users/cltbld', 15:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:17 INFO - 'LOGNAME': 'cltbld', 15:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:17 INFO - 'MOZ_NO_REMOTE': '1', 15:12:17 INFO - 'NO_EM_RESTART': '1', 15:12:17 INFO - 'PAGER': '/bin/cat', 15:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:17 INFO - 'PWD': '/builds/slave/test', 15:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:17 INFO - 'SHELL': '/bin/bash', 15:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:17 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:17 INFO - 'USER': 'cltbld', 15:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:17 INFO - 'XPC_FLAGS': '0x0', 15:12:17 INFO - 'XPC_SERVICE_NAME': '0', 15:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-vyd84l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-na4raL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-aC1I2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-LJvVoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-mFLQiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:17 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)) 15:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:17 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-hqNZxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-S8iPM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-KX1z3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-s3AA4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-rqXgNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-eX7AJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-f4lGg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-e8tv3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-8USW39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-JroGoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:18 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)) 15:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:18 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-ltNsgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:19 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-lirN3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:19 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)) 15:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:19 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)) 15:12:19 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)) 15:12:19 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)) 15:12:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:21 INFO - Downloading blessings-1.6.tar.gz 15:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:12:21 INFO - Installing collected packages: blessings 15:12:21 INFO - Running setup.py install for blessings 15:12:22 INFO - Successfully installed blessings 15:12:22 INFO - Cleaning up... 15:12:22 INFO - Return code: 0 15:12:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:22 INFO - Reading from file tmpfile_stdout 15:12:22 INFO - Current package versions: 15:12:22 INFO - blessings == 1.6 15:12:22 INFO - blobuploader == 1.2.4 15:12:22 INFO - docopt == 0.6.1 15:12:22 INFO - functools32 == 3.2.3-2 15:12:22 INFO - jsonschema == 2.5.1 15:12:22 INFO - manifestparser == 1.1 15:12:22 INFO - mozInstall == 1.12 15:12:22 INFO - mozcrash == 1.0 15:12:22 INFO - mozdebug == 0.1 15:12:22 INFO - mozdevice == 0.48 15:12:22 INFO - mozfile == 1.2 15:12:22 INFO - mozhttpd == 0.7 15:12:22 INFO - mozinfo == 0.9 15:12:22 INFO - mozleak == 0.1 15:12:22 INFO - mozlog == 3.4 15:12:22 INFO - moznetwork == 0.27 15:12:22 INFO - mozprocess == 0.23 15:12:22 INFO - mozprofile == 0.28 15:12:22 INFO - mozrunner == 6.13 15:12:22 INFO - mozscreenshot == 0.1 15:12:22 INFO - mozsystemmonitor == 0.3 15:12:22 INFO - moztest == 0.8 15:12:22 INFO - mozversion == 1.4 15:12:22 INFO - psutil == 3.1.1 15:12:22 INFO - requests == 1.2.3 15:12:22 INFO - wsgiref == 0.1.2 15:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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')]} 15:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:22 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:22 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 15:12:22 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 15:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:22 INFO - 'HOME': '/Users/cltbld', 15:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:22 INFO - 'LOGNAME': 'cltbld', 15:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:22 INFO - 'MOZ_NO_REMOTE': '1', 15:12:22 INFO - 'NO_EM_RESTART': '1', 15:12:22 INFO - 'PAGER': '/bin/cat', 15:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:22 INFO - 'PWD': '/builds/slave/test', 15:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:22 INFO - 'SHELL': '/bin/bash', 15:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:22 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:22 INFO - 'USER': 'cltbld', 15:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:22 INFO - 'XPC_FLAGS': '0x0', 15:12:22 INFO - 'XPC_SERVICE_NAME': '0', 15:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 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)) 15:12:22 INFO - Cleaning up... 15:12:22 INFO - Return code: 0 15:12:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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')]} 15:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:22 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 0x10d390490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d306570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe878574ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, '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.guK6pG9rwN/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.61DtbK6kAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 15:12:22 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 15:12:22 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 15:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:22 INFO - 'HOME': '/Users/cltbld', 15:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:22 INFO - 'LOGNAME': 'cltbld', 15:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:22 INFO - 'MOZ_NO_REMOTE': '1', 15:12:22 INFO - 'NO_EM_RESTART': '1', 15:12:22 INFO - 'PAGER': '/bin/cat', 15:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:22 INFO - 'PWD': '/builds/slave/test', 15:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:22 INFO - 'SHELL': '/bin/bash', 15:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:22 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:22 INFO - 'USER': 'cltbld', 15:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:22 INFO - 'XPC_FLAGS': '0x0', 15:12:22 INFO - 'XPC_SERVICE_NAME': '0', 15:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:12:23 INFO - Cleaning up... 15:12:23 INFO - Return code: 0 15:12:23 INFO - Running post-action listener: _resource_record_post_action 15:12:23 INFO - Running post-action listener: _start_resource_monitoring 15:12:23 INFO - Starting resource monitoring. 15:12:23 INFO - [mozharness: 2017-01-14 23:12:23.048339Z] Finished create-virtualenv step (success) 15:12:23 INFO - [mozharness: 2017-01-14 23:12:23.049351Z] Running install step. 15:12:23 INFO - Running pre-action listener: _resource_record_pre_action 15:12:23 INFO - Running main action method: install 15:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:23 INFO - Reading from file tmpfile_stdout 15:12:23 INFO - Detecting whether we're running mozinstall >=1.0... 15:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:12:23 INFO - Reading from file tmpfile_stdout 15:12:23 INFO - Output received: 15:12:23 INFO - Usage: mozinstall [options] installer 15:12:23 INFO - Options: 15:12:23 INFO - -h, --help show this help message and exit 15:12:23 INFO - -d DEST, --destination=DEST 15:12:23 INFO - Directory to install application into. [default: 15:12:23 INFO - "/builds/slave/test"] 15:12:23 INFO - --app=APP Application being installed. [default: firefox] 15:12:23 INFO - mkdir: /builds/slave/test/build/application 15:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:12:54 INFO - Reading from file tmpfile_stdout 15:12:54 INFO - Output received: 15:12:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:12:54 INFO - Running post-action listener: _resource_record_post_action 15:12:54 INFO - [mozharness: 2017-01-14 23:12:54.405663Z] Finished install step (success) 15:12:54 INFO - [mozharness: 2017-01-14 23:12:54.405811Z] Running run-tests step. 15:12:54 INFO - Running pre-action listener: _resource_record_pre_action 15:12:54 INFO - Running main action method: run_tests 15:12:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:12:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:12:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:12:54 INFO - 2017-01-14 15:12:54.420 screenresolution[1638:13552] starting screenresolution argv=screenresolution get 15:12:54 INFO - 2017-01-14 15:12:54.422 screenresolution[1638:13552] Display 0: 1600x1200x32@60 15:12:54 INFO - 2017-01-14 15:12:54.428 screenresolution[1639:13556] starting screenresolution argv=screenresolution list 15:12:54 INFO - Available Modes on Display 0 15:12:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:12:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:12:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:12:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:12:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:12:54 INFO - Intel Iris: 15:12:54 INFO - Chipset Model: Intel Iris 15:12:54 INFO - Type: GPU 15:12:54 INFO - Bus: Built-In 15:12:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:12:54 INFO - Vendor: Intel (0x8086) 15:12:54 INFO - Device ID: 0x0a2e 15:12:54 INFO - Revision ID: 0x0009 15:12:54 INFO - Displays: 15:12:54 INFO - Display: 15:12:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:12:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:12:54 INFO - Display Serial Number: 1600x1200 60 15:12:54 INFO - Main Display: Yes 15:12:54 INFO - Mirror: Off 15:12:54 INFO - Online: Yes 15:12:54 INFO - Rotation: Supported 15:12:54 INFO - Return code: 0 15:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:12:54 INFO - Python 2.7.3 15:12:54 INFO - Return code: 0 15:12:54 INFO - grabbing minidump binary from tooltool 15:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:54 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 0x10d1a4d30>, '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 0x10d00adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d19cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:12:54 INFO - Return code: 0 15:12:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:12:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:12:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:12:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:12:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 15:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --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 15:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.guK6pG9rwN/Render', 15:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:54 INFO - 'HOME': '/Users/cltbld', 15:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:54 INFO - 'LOGNAME': 'cltbld', 15:12:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:54 INFO - 'MOZ_NO_REMOTE': '1', 15:12:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:54 INFO - 'NO_EM_RESTART': '1', 15:12:54 INFO - 'PAGER': '/bin/cat', 15:12:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:54 INFO - 'PWD': '/builds/slave/test', 15:12:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:54 INFO - 'SHELL': '/bin/bash', 15:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61DtbK6kAn/Listeners', 15:12:54 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 15:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:54 INFO - 'USER': 'cltbld', 15:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:54 INFO - 'XPC_FLAGS': '0x0', 15:12:54 INFO - 'XPC_SERVICE_NAME': '0', 15:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 15:12:55 INFO - mozversion application_buildid: 20170114140724 15:12:55 INFO - mozversion application_changeset: 1e04cc4582dd86864035f0aed5fac81907dea848 15:12:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:12:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:12:55 INFO - mozversion application_name: Firefox 15:12:55 INFO - mozversion application_remotingname: firefox-dev 15:12:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:55 INFO - mozversion application_vendor: Mozilla 15:12:55 INFO - mozversion application_version: 52.0a2 15:12:55 INFO - mozversion platform_buildid: 20170114140724 15:12:55 INFO - mozversion platform_changeset: 1e04cc4582dd86864035f0aed5fac81907dea848 15:12:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:55 INFO - mozversion platform_version: 52.0a2 15:12:55 INFO - using testdate: 1484435575 15:12:55 INFO - actual date: 1484435575 15:12:55 INFO - starting webserver on 'localhost:49245' 15:12:55 INFO - SUITE-START | Running 2 tests 15:12:55 INFO - TEST-START | dromaeo_css 15:12:55 INFO - operating with platform_type : mac_ 15:12:55 INFO - Initialising browser for dromaeo_css test... 15:12:55 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpElrVDE/profile http://localhost:49245/getInfo.html) 15:12:58 INFO - PROCESS | 1649 | 2017-01-14 15:12:58.150 plugin-container[1652:13735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 15:12:58 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:58 INFO - PROCESS | 1649 | 2017-01-14 15:12:58.153 plugin-container[1652:13735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e23, name = 'com.apple.CFPasteboardClient' 15:12:58 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:58 INFO - PROCESS | 1649 | 2017-01-14 15:12:58.153 plugin-container[1652:13735] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:12:58 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 15:12:58 INFO - PROCESS | 1649 | colorDepth:24 15:12:58 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 15:12:58 INFO - PROCESS | 1649 | __metrics 15:12:58 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 15:12:59 INFO - TEST-INFO | 1649: exit 0 15:12:59 INFO - Browser initialized. 15:12:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:59 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpElrVDE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:01 INFO - PROCESS | 1653 | 2017-01-14 15:13:01.968 plugin-container[1654:13864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884f, name = 'com.apple.tsm.portname' 15:13:01 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:01 INFO - PROCESS | 1653 | 2017-01-14 15:13:01.969 plugin-container[1654:13864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:13:01 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:13:01 INFO - PROCESS | 1653 | 2017-01-14 15:13:01.969 plugin-container[1654:13864] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:13:11 INFO - PROCESS | 1653 | RSS: Main: 229113856 15:13:11 INFO - PROCESS | 1653 | 15:16:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 15:16:16 INFO - PROCESS | 1653 | RSS: Main: 234721280 15:16:16 INFO - PROCESS | 1653 | 15:19:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 15:19:18 INFO - PROCESS | 1653 | RSS: Main: 243507200 15:19:18 INFO - PROCESS | 1653 | 15:22:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 15:22:21 INFO - PROCESS | 1653 | RSS: Main: 246874112 15:22:21 INFO - PROCESS | 1653 | 15:25:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 15:25:23 INFO - PROCESS | 1653 | RSS: Main: 246108160 15:25:23 INFO - PROCESS | 1653 | 15:28:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 15:28:27 INFO - PROCESS | 1653 | RSS: Main: 250511360 15:28:27 INFO - PROCESS | 1653 | 15:31:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 15:31:30 INFO - PROCESS | 1653 | RSS: Main: 251904000 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | __start_tp_report 15:31:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 15:31:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 15:31:30 INFO - PROCESS | 1653 | |i|pagename|runs| 15:31:30 INFO - PROCESS | 1653 | |0;dojo.html;3333.100062982996;3350.3299340131966;3373.2747459296247;3389.1527118220447;3390.288039511702;5624.521915637166;5646.915296270554;5666.57500687448;5680;5713.885722285562;5601.29983752031;5649.49154576088;5653.236813030239;5668.57485688574;5668.858278543028;5115.4884511548735;5123;5158.767855446496;5219;5245.449227831061;5208.0365132450615;5226.451222621627;5259.710715910624;5260.526552610264;5291.973540132294;4798.640101992364;5059.342285502861;5103.413107492649;5180.45605211453;5217.765200565984;5061.190209566452;5126.128558145124;5128.076946149691;5130.820421285274;5136.589072874199;3982.880513584597;4221.345691417835;5064.620153488503;5182.611304152204;5190.169572868361;5534.418886016951;5671.7164141793155;5677.574181936371;5687.40282270364;5691.51622112121;6162.815115546541;6177.474914632262;6189.102580125901;6202.751889924398;6236.064590311445;6222.751089956397;6268.153799237136;6271.090691849657;6279.7174127163935;6302.086197501382;5632.295963004624;5645.633033852787;5815.79644712433;5827.184194212814;5844.094165404369;5343.305370301836;5568.192612071227;5972.835297117021;6243.313235544087;6247.437730604267;5162.793488260428;5801.868635616099;6097.573169878155;6113.23584551931;6126.448619624255;5736.196932429463;6040.305364883006;6232;6246.281677607042;6266.185395898504;5908.084246941731;5934.970325148433;6055.515558755289;6066.787662431793;6130.049842274455;5986.880262394728;5989.580729348903;6067.939320606737;6068.33248342682;6144.41628045335;4829.44461386945;4839.516048395132;4839.9758001210475;4847.612191024709;4854.199057155601;4300.053988122608;4335.002949321673;4342;4349.28236840924;4355.825766969286;4872.415310162733;5595.440455954372;5738.454846789548;5760.596758226968;5829.09649004405;6130.29501607312;6249.0938813871735;6267.185265915402;6274.905876411857;6281.59169653971;5631.324241091095;5637.46444087811;5686.175504551817;5689.203511508392;5744.109663002241;5306.097963346163;5322.813701520428;5328.653637513627;5332.626716129911;5346.385165705994;6213.751449941951;6254.249490061222;6260.655663938527;6269.247690277196;6302.369763023661;6106.664133472702;6112.351504965139;6156.815295541141;6157.322694503511;6215.9378406215355;6257.5932564383165;6277.18396608438;6279;6288.779892703734;6328.746850125944;6127.785527506516;6146.877062458815;6152.507799376129;6171.59884607499;6187.948048831619;4902.877428064185;5987.43119403656;6112.388761123852;6121.666376154927;6228;5975.671338076448;6103.511719062553;6124.8775024500155;6135.569531795875;6177.660228687465;5371.731413429391;6090.390960903874;6096.533431718184;6110.4980065817745;6138.416850399205;4283.485981682218;5846.41535846412;5927.525797936069;5963.73163207648;6015.57890947647;5945.227120474311;5971.850703732442;5984.760609575568;6034.758609655564;6066.605670631571;5145.794168233228;5905.557083218861;5916.112583112395;5929.822105336847;5946.07835785454 15:31:30 INFO - PROCESS | 1653 | |1;ext.html;463.78202244944885;501.9648624596275;505.8330750852219;519.7115600841531;565.5277843001093;36599.63400365996;38481;38587.807060964726;38603.613963860356;38603.99999999996;39400;39589.8123056308;39652.206955860864;39961.80019099908;40044;9232.6306947722;9266.814663706706;9384.718458446258;9401.435913845156;9416.952915235413;8870.556472176399;8943.373963822534;8982.146696063863;9047.140521650434;9050.637974481013;8936.329775266848;9004.279657627374;9037.864432033492;9067.183953444188;9097.044810294923;8976.685815996409;8984.595693193789;9033.187013168781;9059.68291109808;9075.773105672411;8238.846561481398;9173.174414302677;9358.11097945694;9367.718968430958;9427.292953028551;37834.43248351277;37892.86321410362;37955.05112372185;38151.61848381509;38157;3279.1638132276357;3398.9660103398896;3504.1590018395655;3816.46569480273;3978.0452691353867;28264.576031359546;29453.116406507837;29695.703042969726;30049.248768780737;30202.54696179559;3173.174974506645;3191.6329622093526;3198.616166060064;3204.96795032049;3220.951685724716;517298;517589;519346;519614;520753;48930.51069489331;53079;56114.719426402604;56359.43640563542;56449.71775141098;56992.43007569954;57488;57830.42169578335;57881;57924.420755792744;15823.841761582467;15953.840461595235;16040.919795401182;16044.919775401282;16071;12272.32502212369;12578;12619.179753316004;12699.174553653984;12719;14301.642458938402;14423;14668.339924703414;14694.971352005257;14823.25883705832;6355.904661430082;6482.481401487869;6529.967350163219;6570.145881035531;6579.901301480481;827.5407149032345;928.8095940332215;939.3377668743519;943.1370296178919;998.6205242007991;29087.41825163485;29298.868371660206;29537.852310738308;29715.85142074319;29857.402851943272;1055.0873494427265;1062.7555662197738;1078.7087486378634;1085.1427372375736;1147.6958605969257;12310.446029928675;13256.337183140804;14413.774829139431;15138.924305378403;15316.923415382851;659150;660343;665133;666405;668629;45886.31170532445;48000.79998000008;54946;55446.89106217853;55568.72215638977;1327.6879933215687;1354.674878029266;1368.5278578890268;1369.137443410645;1375.4498200719793;28904;29296.707032929397;29565.556516651835;30030.69969300279;30160.24599385033;47030.29454558116;47726.284105739134;47828;48178.518214817406;48236.03527929492;631728;633952;634723;637304;637718;25651.10221142251;25667.486650266517;25896.741032589434;26675.466490670467;27164.1850744478;45058.09883802371;45200.095998079196;45470;45587.77206113948;45726.77136614296;36921;37347.62652373441;37740.81129594334;38403.807980961035;38809.41785873158;37483.43774843325;37528.81235593805;38009.239815202985;38377;38930.8320750378;35580.28839423249;36161;36726.08184795402;36977.815110924275;37234.813825930694;32612.021639350856;33628.32743345168;33988.83005584956;33997.3200535983;34219.82890085533;25282.873585631954;25334.74665253324;25483.872580636977;25580.616290755283;25777.871110644326 15:31:30 INFO - PROCESS | 1653 | |2;jquery.html;348.62348663443476;368.40686494743477;380.05935310107486;382.6020938224247;397.2313573235789;18651.507879369652;19124;19340.709889351634;19875.900620496894;20180.596388072267;9881.45651989142;11035.558577656904;11424.200305978586;11875.940620296908;11897.58358457452;4134.710570260083;5849.795257165979;5911.9113213301835;5922.496587790043;5932.762689492415;3848.1918797052654;4070.715049946505;5818.447247511501;6294.748210071614;6587.044878492616;6274.968625156891;6612.173478315211;6660.633665148439;6672.933270667279;6675.2323482799375;5938.64368137911;5968.820935371924;6065.787697430613;6069.180660610805;6079.361667024965;5727.713614319267;5794.159846822229;5851.824445266649;5892.587518873681;5919.970400148015;13163.802542961863;13217.537386191438;13245.735085298336;13315.134516256412;21569.1372345106;2194.4513871532117;2205.9558808823735;2292.988535057314;2370.549595576852;2478;9562.952185239099;9780.706578802647;10070.244731645156;10079.596816127347;10280.640177593748;1774.1218097042047;1854.2953677602425;1855.8422534084484;1870.2892900697784;1936.5836345185853;110609.34085988636;115475.42262288718;116214;122331;122703.386483067;9228.8615670765;11262.155338349656;11379.317240965569;11543.480543375568;11686.065114790796;14294;14575.41698332076;14581.76055035334;14616;14635.926820366043;8222.095569487352;8458.577071146541;8516.361272904433;8550.144985501525;8645.913540864636;8275.50346979183;8418.368622353226;8466.280366168821;8562.229399353964;8580.528070956158;4381.364702118175;4429.579189976947;4479.372887795711;4512.9097418052115;4623.583877451046;1696.5334533003522;1709.8116808817751;1821.4080423862297;1903.7625543396903;1910.4650697804636;753.6947536247719;860.2859626509982;871.4335681806876;871.4814685262259;954.3415043620018;15362.462313818962;16593.91703041477;16693.415730449375;19535.316263930694;20476.69284960727;897.1521911793293;897.304588943574;937.128470522407;943.2642538819731;974.0357046523891;10334.896651033543;10615.84076238857;10699.411532365639;10716.196285278635;10736.355818650905;512346;538706;551236;558036;561464;102265;122248;124490.13264801035;130003.34998324949;131560.3421982884;930.8789857318505;941.3551717073826;950.3395140377371;950.5722424908819;954.7804005078732;16805.41181058706;17498.562535936704;18016.099195040457;18067.728984065514;18123.275068997093;43058.78470607627;51002;51467.74266128645;52618.473815262885;53077.7346113267;74660;76604.616976917;76847;76961.61519192593;77083;7838.529688218725;7895.210478952288;7954.403419743659;8081.878771818545;8126.309263712651;11802.527898884291;11955.103367247317;12329.260244385365;12392.62822115338;12686.175398599062;5138.698282311389;5305.840824775263;10508.684739457829;10791.460426978776;10970.451477425937;10623.203259755395;10781.299215550964;10865.07646849995;10917.83623245636;11117.722056948642;10911.127109831354;11016.17378696617;11065.668029959113;11188.776224475629;11420.200585959235;7899.960500197462;7970.083440404428;8117.918820811952;8210.876836847332;8218.301444377295;10555.419451930218;11051;11127.1654625905;11187.608433704781;11236.98867101932 15:31:30 INFO - PROCESS | 1653 | |3;mootools.html;1249.7438025204833;1443.0043270143603;1584.3266611690028;1776.2184638758954;1782.37616834108;7525.059367579052;7786.377089832814;7989.8402031959395;8492.53291068991;8510.617022234006;7916.050073991115;8250.628721707522;8268;8322.167783221676;8340.3744719146;2318.0611852199845;2356.905723771047;2366.668666386707;2369.18263199196;2379.3694670912223;1970.9605807883834;2345.8944347504316;2369.2655276864143;2376.738558758535;2399.8200134989856;1896.7534220551308;1925.4608709561346;2333.94165145871;2349.5653304138705;2359.6696462495265;2235.564065007324;2242.8990695418747;2260.457490202356;2262.366537369539;2264.230161745006;2290.0496294037953;2343.414146463384;2354.799842013431;2359.7522260162777;2363.4918492524016;8309.667613295462;8309.667613295462;8372.03721572021;8376.664933402717;8389.07720150783;4123.60825721556;5244.947550524484;5247.055530004598;5250.264962905197;5255.211718242294;3253.3330667213163;3333.5333053372306;3544.9645503544893;3556.324298383299;3566.928661426783;1053.5733028123623;1063.1335461598771;1082.815921293382;1093.4915264402011;1095.4413249242943;10838.67483975482;10866.565337386495;10901.073408760345;10930;10956.397398143099;4527.3435351873795;4622.375979242759;4707.388039554904;4724.102420540086;4762.880927976759;9413.623455061721;9426.387284826464;9447.527623618791;9457.76355591102;9458.243340532741;5206.505381988705;5216.40011398694;5221.477852214824;5224.085784987612;5230.947690523122;4268.615824575803;4273.423087883096;4726.385569875894;4785.090832741768;4792.0895029944195;3144.685531446837;3801.2207497462955;3878.3406820840523;3921.0001449630163;3930.6069393060466;1197.6227488341144;1269.2574843716345;1272.3129510064646;1276.425608476171;1276.942537585811;819.5246756881114;879.3712495565701;907.0476000199764;907.5734404829719;913.57975331347;4874.634402419832;5628.448926118557;5711.343195532567;5805.88388232233;6007.939920600826;819.9262066414051;903.8599017152352;941.7033634405141;958.6596758150896;968.080323692495;3445.1903802606366;3774.509313789245;3775.5846856845724;3870.40008798631;3896.473976013259;8236.423450358538;9881.407115573089;9978.550965206583;10130.341527800669;10234.846477302846;9904.653337133166;10052.597896084075;10160.949195254125;10200.38797672142;10211.029952154533;7856.135825059239;7907.327877130393;7909.920900791033;7918.247766462292;8029.879551806727;4502.414686090919;4522.412086428743;5479.780808767605;5519.31008623922;5732.484076433141;9284.442933424016;9307.11582399671;9341.532923353941;9518.428894266366;9544.713658590254;9466.668666596635;9520.523973801144;9530.046995300414;9620.13418792312;9729.270304727032;6597.60414375139;6769.458443324621;6855.931440685529;6863.656817159222;6874.8625027500175;7842.098158711821;7869.960650196713;7918;7926.920730792618;7934.444588878723;7145.428365730625;7171.354578087997;7199.532030418006;7313.597752123685;7378.114626244885;6932.0988271524375;7054.717811287491;7118.430525558047;7132.1441427029085;7138.85722285541;6634.436072933855;6746.561473504206;6762.830929226809;6767.560108593123;6778;4854.854354369375;4870.829520966749;4986.9251961221335;5012.573931215888;5054.4187418447345;4939.60483161339;4972.900541989212;5048.167052436307;5080.161773307363;5090.720010399464 15:31:30 INFO - PROCESS | 1653 | |4;prototype.html;302.41935483870964;305.7355387589736;308.76071044940176;315.7474020783374;316.7260319823351;5189.247559103927;5431.945680543194;5471.945280547194;5548;5600.551955843533;4546.45442546894;4729.739864307462;5267.894642107156;5502.58730595206;5524.287470884029;4180.912962629715;4185.414042034103;4206.053637931456;4216.406909750497;4218.578142185772;3429.159855835324;3990.4014397840265;4143.295639741251;4226.3517228281;4235.555266696983;3405.829708514577;4115.950432639687;4118.320477121287;4138.654937145425;4157.812898419564;4121.010957370225;4146.626803587692;4149.2738770715005;4168.291390463629;4180.728252663567;3046.6039414875922;3537.9469307960403;3597.5143355646915;3598.7300952428673;3604.6936010439144;4354.956450435487;4629.125204292267;5065.746712664352;5368;5437.673739575598;3843.693144330941;4163.458750362463;4753.78607962639;4761.523847615245;4792.496787837296;5101.69389836611;5821.417858214187;5865.325487568942;5885.146653735227;5904.645721256696;3504.759241318008;3812.3137835189655;3897.337452633059;3942.448057271984;3957.3668213086057;5810.970945145289;5958.152972578471;5969.373215812364;6096.7561297548045;6213.5339849511465;3420.709239714652;3941.0935484838074;4005.1989602079702;4008.8396464141106;4012.558618551957;3920.4511368408444;3991.880243592697;4017.754496106158;4044.492414702844;4073.7148399612333;3925.2738243424487;4128.876133715993;4314.913701725948;4605.539446055434;4710.929336059962;5863.3257175424515;5881.118042226532;5903.970480147657;5918.763249470059;5950.7917222896995;1061.946902654864;1250.2685928731612;1267.9112462127562;1300.4928078049568;1322.623052430072;748.3938010211746;842.6208206307186;850.9319254459753;857.9442336248123;876.3646356391565;4856.028794241166;5020.924686129711;5069.062223488666;5147.922781158286;5246.291750613695;5025.346704928336;5667.299810056967;5670.121131224667;5755.65466072037;5787.39567194764;4492.3710680504755;4497.550244975541;4906.362172917569;5196.298499702491;5244.868878278074;2669.933251668685;2726.428378823747;2883.78371622125;3058.709422604849;3097.38052389523;5433.565314774809;5547.528460080857;5906.0255057914965;6077.544184186204;6097.847553811191;5121;5156.974215128976;5513.250989327215;5546.223528705985;5740.282464691913;4933.851984440545;5757.769689212385;5819.709014549256;6000.649529091374;6041.848953776191;4693.044974195134;5825.3592104867585;5829;5836.182934389274;5868.67722275279;4778.116048531033;4824.517548245148;4982.6512144149565;5682;5696.148751655811;5598.3128098512125;5771.422857714195;5937.910931335947;6060.181875446935;6076.817695469143;2768.6400767900045;3388.678075582816;3786.659200671953;3796.240751849696;3812.618738126165;5443.074677304789;5528.751206195811;5566.415526369673;5835.424435402502;6057.394260573908;5050.84847454577;5731.627444216113;5827.737751801264;5917;5932.436418540232;5074.31496747949;5229.692576855786;5931.228940237742;5966.582339236212;5984.307061822285;5469.371022332482;5735.426457354231;5762.2221000164445;5827.94172058274;5854.736536855937;5573.359063707726;5578.386377498553;5982.850428739316;5985.820425387245;5987.910181347371;4819.855404337875;5523.309586301712;5853.078437447006;5855.268091488564;5887.146363777143 15:31:30 INFO - PROCESS | 1653 | |5;yui.html;687.5771400588638;745.1468069060926;745.455817253405;781.3631890009639;788.5426452657459;13562.864371356283;13934.51229206978;14151.92924035381;14197.574072777834;14212.147271163725;14024.719505609908;14617.196054217015;14624.634384140376;14900.254987250648;14915.254237288147;636.1348565950304;651.986161518838;774.577855068986;776.2121446731586;799.5322736199302;779.2558107007823;783.9921600783977;797.6450479536609;798.389232237336;802.1096582793095;838.2707044870971;839.2698352433391;842.5323945210406;844.0968164064453;844.9281811046069;801.7795106345773;833.829065041671;839.1566475691922;840.36552402936;841.1210285251896;600.1627729317578;625.4933503861887;626.2703949898363;627.4509803921569;628.2649300318639;13925.791113133311;13943.16341019542;14049.78925316121;14051.578452646438;14268.643283917881;13087.607371778862;13359.53241636538;13394.263315517643;13395.665108372272;13600.65998350049;11293.548258069668;11356.48895799691;11358.886411135949;11574.482423713876;11865.40672966357;3555.3600351936643;3612.3858943979585;3677.1358730698266;3693.4644476550748;3696.482492451059;57050;83194.58402707947;86679;88373;89046.21907561878;10111.090001899716;10193.949030254802;10268.229882758824;10393.58425662965;10476.842847357295;19901.800981990284;19949.202031918852;19968;20286.884221367967;20288.898555507127;7492.70029198826;7502.099748030271;7521.135069466972;7584.924150758422;7718.691252349843;4013.237484877864;4201.474815648044;4281.957180428218;4333.609975102256;4347.630451411602;2672.4521473098357;3337.8497967591516;3444.190615205425;3446.379651662725;3465.480177973274;632.1434456311723;644.690548536705;647.1974751308344;649.7530938243438;664.64773669955;571.5941681406248;572.221778381401;582.3186871360518;625.2247213455373;631.3528633151057;8606.698765543177;8777.253933415732;9067.54662266884;9092.181703646704;9111.134442227978;552.1165292983554;609.8108688058215;612.2163630552899;641.9935800642028;662.8309781005813;4063.7764922929523;4092.672586193157;4396.538363471854;4439.933400998988;4523.864284071483;79242;108678;111018.44490777495;116778;124420.1336979946;95433.0456695438;96810;98569;100263.49868250612;101423;54377.3686789389;69966.30033699598;72955.63522182356;89608.10391896128;89623.55188224147;8196.56607440998;9183.908160918305;9562.56968436436;9601.615935362786;9760.365576237522;41603.58396416118;49227.507724922296;51500;53721;54061.03005660515;58457;71948;75001;75351.24648753442;77484.61257693675;12046.75906481883;12063.698407539881;12177.208481448677;12266.9283611384;12361.134720569475;13108.279044652254;13511.639425458148;13514.729705406033;13689.794653080013;14031.579052628438;12485.31330776816;12649.873501265238;12992.415341309475;13010.219386836441;13567.660808479866;12898.806517902427;12965;12977;13239.3380330985;13253.807157355885;10496.317739346918;12467.127301088836;12516.87483125157;12549.435275412447;12957.611271661865;8084.474509156886;9074.274058075207;9178.908210917805;10748.366650170758;11072.335659860435;10507.842382364424;11113;11379.146564007568;11404.94297528507;11410.714732131763 15:31:30 INFO - PROCESS | 1653 | __end_tp_report 15:31:30 INFO - PROCESS | 1653 | __start_cc_report 15:31:30 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,371 15:31:30 INFO - PROCESS | 1653 | __end_cc_report 15:31:30 INFO - PROCESS | 1653 | __startTimestamp1484436690153__endTimestamp 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | ------- Summary: start ------- 15:31:30 INFO - PROCESS | 1653 | Number of tests: 6 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5634.77 Median:5829.10 stddev:667.11 (11.4%) stddev-sans-first:644.26 15:31:30 INFO - PROCESS | 1653 | Values: 3333.1 3350.3 3373.3 3389.2 3390.3 5624.5 5646.9 5666.6 5680.0 5713.9 5601.3 5649.5 5653.2 5668.6 5668.9 5115.5 5123.0 5158.8 5219.0 5245.4 5208.0 5226.5 5259.7 5260.5 5292.0 4798.6 5059.3 5103.4 5180.5 5217.8 5061.2 5126.1 5128.1 5130.8 5136.6 3982.9 4221.3 5064.6 5182.6 5190.2 5534.4 5671.7 5677.6 5687.4 5691.5 6162.8 6177.5 6189.1 6202.8 6236.1 6222.8 6268.2 6271.1 6279.7 6302.1 5632.3 5645.6 5815.8 5827.2 5844.1 5343.3 5568.2 5972.8 6243.3 6247.4 5162.8 5801.9 6097.6 6113.2 6126.4 5736.2 6040.3 6232.0 6246.3 6266.2 5908.1 5935.0 6055.5 6066.8 6130.0 5986.9 5989.6 6067.9 6068.3 6144.4 4829.4 4839.5 4840.0 4847.6 4854.2 4300.1 4335.0 4342.0 4349.3 4355.8 4872.4 5595.4 5738.5 5760.6 5829.1 6130.3 6249.1 6267.2 6274.9 6281.6 5631.3 5637.5 5686.2 5689.2 5744.1 5306.1 5322.8 5328.7 5332.6 5346.4 6213.8 6254.2 6260.7 6269.2 6302.4 6106.7 6112.4 6156.8 6157.3 6215.9 6257.6 6277.2 6279.0 6288.8 6328.7 6127.8 6146.9 6152.5 6171.6 6187.9 4902.9 5987.4 6112.4 6121.7 6228.0 5975.7 6103.5 6124.9 6135.6 6177.7 5371.7 6090.4 6096.5 6110.5 6138.4 4283.5 5846.4 5927.5 5963.7 6015.6 5945.2 5971.9 5984.8 6034.8 6066.6 5145.8 5905.6 5916.1 5929.8 5946.1 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:72144.23 Median:38603.81 stddev:163284.49 (423.0%) stddev-sans-first:163653.85 15:31:30 INFO - PROCESS | 1653 | Values: 463.8 502.0 505.8 519.7 565.5 36599.6 38481.0 38587.8 38603.6 38604.0 39400.0 39589.8 39652.2 39961.8 40044.0 9232.6 9266.8 9384.7 9401.4 9417.0 8870.6 8943.4 8982.1 9047.1 9050.6 8936.3 9004.3 9037.9 9067.2 9097.0 8976.7 8984.6 9033.2 9059.7 9075.8 8238.8 9173.2 9358.1 9367.7 9427.3 37834.4 37892.9 37955.1 38151.6 38157.0 3279.2 3399.0 3504.2 3816.5 3978.0 28264.6 29453.1 29695.7 30049.2 30202.5 3173.2 3191.6 3198.6 3205.0 3221.0 517298.0 517589.0 519346.0 519614.0 520753.0 48930.5 53079.0 56114.7 56359.4 56449.7 56992.4 57488.0 57830.4 57881.0 57924.4 15823.8 15953.8 16040.9 16044.9 16071.0 12272.3 12578.0 12619.2 12699.2 12719.0 14301.6 14423.0 14668.3 14695.0 14823.3 6355.9 6482.5 6530.0 6570.1 6579.9 827.5 928.8 939.3 943.1 998.6 29087.4 29298.9 29537.9 29715.9 29857.4 1055.1 1062.8 1078.7 1085.1 1147.7 12310.4 13256.3 14413.8 15138.9 15316.9 659150.0 660343.0 665133.0 666405.0 668629.0 45886.3 48000.8 54946.0 55446.9 55568.7 1327.7 1354.7 1368.5 1369.1 1375.4 28904.0 29296.7 29565.6 30030.7 30160.2 47030.3 47726.3 47828.0 48178.5 48236.0 631728.0 633952.0 634723.0 637304.0 637718.0 25651.1 25667.5 25896.7 26675.5 27164.2 45058.1 45200.1 45470.0 45587.8 45726.8 36921.0 37347.6 37740.8 38403.8 38809.4 37483.4 37528.8 38009.2 38377.0 38930.8 35580.3 36161.0 36726.1 36977.8 37234.8 32612.0 33628.3 33988.8 33997.3 34219.8 25282.9 25334.7 25483.9 25580.6 25777.9 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:32428.43 Median:374.23 stddev:91388.51 (24420.2%) stddev-sans-first:91613.14 15:31:30 INFO - PROCESS | 1653 | Values: 348.6 368.4 380.1 382.6 397.2 18651.5 19124.0 19340.7 19875.9 20180.6 9881.5 11035.6 11424.2 11875.9 11897.6 4134.7 5849.8 5911.9 5922.5 5932.8 3848.2 4070.7 5818.4 6294.7 6587.0 6275.0 6612.2 6660.6 6672.9 6675.2 5938.6 5968.8 6065.8 6069.2 6079.4 5727.7 5794.2 5851.8 5892.6 5920.0 13163.8 13217.5 13245.7 13315.1 21569.1 2194.5 2206.0 2293.0 2370.5 2478.0 9563.0 9780.7 10070.2 10079.6 10280.6 1774.1 1854.3 1855.8 1870.3 1936.6 110609.3 115475.4 116214.0 122331.0 122703.4 9228.9 11262.2 11379.3 11543.5 11686.1 14294.0 14575.4 14581.8 14616.0 14635.9 8222.1 8458.6 8516.4 8550.1 8645.9 8275.5 8418.4 8466.3 8562.2 8580.5 4381.4 4429.6 4479.4 4512.9 4623.6 1696.5 1709.8 1821.4 1903.8 1910.5 753.7 860.3 871.4 871.5 954.3 15362.5 16593.9 16693.4 19535.3 20476.7 897.2 897.3 937.1 943.3 974.0 10334.9 10615.8 10699.4 10716.2 10736.4 512346.0 538706.0 551236.0 558036.0 561464.0 102265.0 122248.0 124490.1 130003.3 131560.3 930.9 941.4 950.3 950.6 954.8 16805.4 17498.6 18016.1 18067.7 18123.3 43058.8 51002.0 51467.7 52618.5 53077.7 74660.0 76604.6 76847.0 76961.6 77083.0 7838.5 7895.2 7954.4 8081.9 8126.3 11802.5 11955.1 12329.3 12392.6 12686.2 5138.7 5305.8 10508.7 10791.5 10970.5 10623.2 10781.3 10865.1 10917.8 11117.7 10911.1 11016.2 11065.7 11188.8 11420.2 7900.0 7970.1 8117.9 8210.9 8218.3 10555.4 11051.0 11127.2 11187.6 11237.0 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5436.04 Median:5211.45 stddev:2986.08 (57.3%) stddev-sans-first:2977.88 15:31:30 INFO - PROCESS | 1653 | Values: 1249.7 1443.0 1584.3 1776.2 1782.4 7525.1 7786.4 7989.8 8492.5 8510.6 7916.1 8250.6 8268.0 8322.2 8340.4 2318.1 2356.9 2366.7 2369.2 2379.4 1971.0 2345.9 2369.3 2376.7 2399.8 1896.8 1925.5 2333.9 2349.6 2359.7 2235.6 2242.9 2260.5 2262.4 2264.2 2290.0 2343.4 2354.8 2359.8 2363.5 8309.7 8309.7 8372.0 8376.7 8389.1 4123.6 5244.9 5247.1 5250.3 5255.2 3253.3 3333.5 3545.0 3556.3 3566.9 1053.6 1063.1 1082.8 1093.5 1095.4 10838.7 10866.6 10901.1 10930.0 10956.4 4527.3 4622.4 4707.4 4724.1 4762.9 9413.6 9426.4 9447.5 9457.8 9458.2 5206.5 5216.4 5221.5 5224.1 5230.9 4268.6 4273.4 4726.4 4785.1 4792.1 3144.7 3801.2 3878.3 3921.0 3930.6 1197.6 1269.3 1272.3 1276.4 1276.9 819.5 879.4 907.0 907.6 913.6 4874.6 5628.4 5711.3 5805.9 6007.9 819.9 903.9 941.7 958.7 968.1 3445.2 3774.5 3775.6 3870.4 3896.5 8236.4 9881.4 9978.6 10130.3 10234.8 9904.7 10052.6 10160.9 10200.4 10211.0 7856.1 7907.3 7909.9 7918.2 8029.9 4502.4 4522.4 5479.8 5519.3 5732.5 9284.4 9307.1 9341.5 9518.4 9544.7 9466.7 9520.5 9530.0 9620.1 9729.3 6597.6 6769.5 6855.9 6863.7 6874.9 7842.1 7870.0 7918.0 7926.9 7934.4 7145.4 7171.4 7199.5 7313.6 7378.1 6932.1 7054.7 7118.4 7132.1 7138.9 6634.4 6746.6 6762.8 6767.6 6778.0 4854.9 4870.8 4986.9 5012.6 5054.4 4939.6 4972.9 5048.2 5080.2 5090.7 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4599.52 Median:5111.35 stddev:1451.92 (28.4%) stddev-sans-first:1419.72 15:31:30 INFO - PROCESS | 1653 | Values: 302.4 305.7 308.8 315.7 316.7 5189.2 5431.9 5471.9 5548.0 5600.6 4546.5 4729.7 5267.9 5502.6 5524.3 4180.9 4185.4 4206.1 4216.4 4218.6 3429.2 3990.4 4143.3 4226.4 4235.6 3405.8 4116.0 4118.3 4138.7 4157.8 4121.0 4146.6 4149.3 4168.3 4180.7 3046.6 3537.9 3597.5 3598.7 3604.7 4355.0 4629.1 5065.7 5368.0 5437.7 3843.7 4163.5 4753.8 4761.5 4792.5 5101.7 5821.4 5865.3 5885.1 5904.6 3504.8 3812.3 3897.3 3942.4 3957.4 5811.0 5958.2 5969.4 6096.8 6213.5 3420.7 3941.1 4005.2 4008.8 4012.6 3920.5 3991.9 4017.8 4044.5 4073.7 3925.3 4128.9 4314.9 4605.5 4710.9 5863.3 5881.1 5904.0 5918.8 5950.8 1061.9 1250.3 1267.9 1300.5 1322.6 748.4 842.6 850.9 857.9 876.4 4856.0 5020.9 5069.1 5147.9 5246.3 5025.3 5667.3 5670.1 5755.7 5787.4 4492.4 4497.6 4906.4 5196.3 5244.9 2669.9 2726.4 2883.8 3058.7 3097.4 5433.6 5547.5 5906.0 6077.5 6097.8 5121.0 5157.0 5513.3 5546.2 5740.3 4933.9 5757.8 5819.7 6000.6 6041.8 4693.0 5825.4 5829.0 5836.2 5868.7 4778.1 4824.5 4982.7 5682.0 5696.1 5598.3 5771.4 5937.9 6060.2 6076.8 2768.6 3388.7 3786.7 3796.2 3812.6 5443.1 5528.8 5566.4 5835.4 6057.4 5050.8 5731.6 5827.7 5917.0 5932.4 5074.3 5229.7 5931.2 5966.6 5984.3 5469.4 5735.4 5762.2 5827.9 5854.7 5573.4 5578.4 5982.9 5985.8 5987.9 4819.9 5523.3 5853.1 5855.3 5887.1 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:19851.88 Median:4372.08 stddev:29148.65 (666.7%) stddev-sans-first:29194.90 15:31:30 INFO - PROCESS | 1653 | Values: 687.6 745.1 745.5 781.4 788.5 13562.9 13934.5 14151.9 14197.6 14212.1 14024.7 14617.2 14624.6 14900.3 14915.3 636.1 652.0 774.6 776.2 799.5 779.3 784.0 797.6 798.4 802.1 838.3 839.3 842.5 844.1 844.9 801.8 833.8 839.2 840.4 841.1 600.2 625.5 626.3 627.5 628.3 13925.8 13943.2 14049.8 14051.6 14268.6 13087.6 13359.5 13394.3 13395.7 13600.7 11293.5 11356.5 11358.9 11574.5 11865.4 3555.4 3612.4 3677.1 3693.5 3696.5 57050.0 83194.6 86679.0 88373.0 89046.2 10111.1 10193.9 10268.2 10393.6 10476.8 19901.8 19949.2 19968.0 20286.9 20288.9 7492.7 7502.1 7521.1 7584.9 7718.7 4013.2 4201.5 4282.0 4333.6 4347.6 2672.5 3337.8 3444.2 3446.4 3465.5 632.1 644.7 647.2 649.8 664.6 571.6 572.2 582.3 625.2 631.4 8606.7 8777.3 9067.5 9092.2 9111.1 552.1 609.8 612.2 642.0 662.8 4063.8 4092.7 4396.5 4439.9 4523.9 79242.0 108678.0 111018.4 116778.0 124420.1 95433.0 96810.0 98569.0 100263.5 101423.0 54377.4 69966.3 72955.6 89608.1 89623.6 8196.6 9183.9 9562.6 9601.6 9760.4 41603.6 49227.5 51500.0 53721.0 54061.0 58457.0 71948.0 75001.0 75351.2 77484.6 12046.8 12063.7 12177.2 12266.9 12361.1 13108.3 13511.6 13514.7 13689.8 14031.6 12485.3 12649.9 12992.4 13010.2 13567.7 12898.8 12965.0 12977.0 13239.3 13253.8 10496.3 12467.1 12516.9 12549.4 12957.6 8084.5 9074.3 9178.9 10748.4 11072.3 10507.8 11113.0 11379.1 11404.9 11410.7 15:31:30 INFO - PROCESS | 1653 | -------- Summary: end -------- 15:31:30 INFO - PROCESS | 1653 | 15:31:30 INFO - TEST-INFO | 1653: exit 0 15:31:30 INFO - TEST-OK | dromaeo_css | took 1115904ms 15:31:30 INFO - TEST-START | kraken 15:31:30 INFO - operating with platform_type : mac_ 15:31:30 INFO - Initialising browser for kraken test... 15:31:30 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpNdCCYI/profile http://localhost:49245/getInfo.html) 15:31:33 INFO - PROCESS | 1662 | 2017-01-14 15:31:33.712 plugin-container[1663:16177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 15:31:33 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:33 INFO - PROCESS | 1662 | 2017-01-14 15:31:33.714 plugin-container[1663:16177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 15:31:33 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:33 INFO - PROCESS | 1662 | 2017-01-14 15:31:33.714 plugin-container[1663:16177] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:31:33 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 15:31:33 INFO - PROCESS | 1662 | colorDepth:24 15:31:33 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 15:31:33 INFO - PROCESS | 1662 | __metrics 15:31:33 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 15:31:34 INFO - TEST-INFO | 1662: exit 0 15:31:34 INFO - Browser initialized. 15:31:34 INFO - Running cycle 1/1 for kraken test... 15:31:34 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpNdCCYI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:37 INFO - PROCESS | 1664 | 2017-01-14 15:31:37.110 plugin-container[1665:16299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423b, name = 'com.apple.tsm.portname' 15:31:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:37 INFO - PROCESS | 1664 | 2017-01-14 15:31:37.110 plugin-container[1665:16299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:31:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:37 INFO - PROCESS | 1664 | 2017-01-14 15:31:37.110 plugin-container[1665:16299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:31:46 INFO - PROCESS | 1664 | RSS: Main: 229298176 15:31:46 INFO - PROCESS | 1664 | 15:31:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 15:31:50 INFO - PROCESS | 1664 | RSS: Main: 241238016 15:31:50 INFO - PROCESS | 1664 | 15:31:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 15:31:53 INFO - PROCESS | 1664 | RSS: Main: 249339904 15:31:53 INFO - PROCESS | 1664 | 15:31:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 15:31:57 INFO - PROCESS | 1664 | RSS: Main: 253014016 15:31:57 INFO - PROCESS | 1664 | 15:31:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 15:32:00 INFO - PROCESS | 1664 | RSS: Main: 254443520 15:32:00 INFO - PROCESS | 1664 | 15:32:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:32:03 INFO - PROCESS | 1664 | RSS: Main: 254648320 15:32:03 INFO - PROCESS | 1664 | 15:32:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 15:32:06 INFO - PROCESS | 1664 | RSS: Main: 258568192 15:32:06 INFO - PROCESS | 1664 | 15:32:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 15:32:10 INFO - PROCESS | 1664 | RSS: Main: 258068480 15:32:10 INFO - PROCESS | 1664 | 15:32:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 15:32:13 INFO - PROCESS | 1664 | RSS: Main: 258199552 15:32:13 INFO - PROCESS | 1664 | 15:32:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:32:16 INFO - PROCESS | 1664 | RSS: Main: 256933888 15:32:16 INFO - PROCESS | 1664 | 15:32:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:32:18 INFO - PROCESS | 1664 | RSS: Main: 256966656 15:32:18 INFO - PROCESS | 1664 | 15:32:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:32:21 INFO - PROCESS | 1664 | RSS: Main: 256933888 15:32:21 INFO - PROCESS | 1664 | 15:32:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:25 INFO - PROCESS | 1664 | RSS: Main: 257269760 15:32:25 INFO - PROCESS | 1664 | 15:32:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:28 INFO - PROCESS | 1664 | RSS: Main: 257404928 15:32:28 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 15:32:30 INFO - PROCESS | 1664 | RSS: Main: 259211264 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | __start_tp_report 15:32:30 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:32:30 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:32:30 INFO - PROCESS | 1664 | |i|pagename|runs| 15:32:30 INFO - PROCESS | 1664 | |0;ai-astar;107;118;118;120;118;118;125;119;118;122 15:32:30 INFO - PROCESS | 1664 | |1;audio-beat-detection;146;113;112;144;115;112;110;112;140;116 15:32:30 INFO - PROCESS | 1664 | |2;audio-dft;197;199;199;197;196;193;196;200;289;246 15:32:30 INFO - PROCESS | 1664 | |3;audio-fft;73;59;63;116;84;59;64;78;102;59 15:32:30 INFO - PROCESS | 1664 | |4;audio-oscillator;82;78;125;105;102;71;87;71;71;88 15:32:30 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;77;96;77;111;135;78;136;74;135;132 15:32:30 INFO - PROCESS | 1664 | |6;imaging-darkroom;98;95;96;106;153;94;96;95;96;93 15:32:30 INFO - PROCESS | 1664 | |7;imaging-desaturate;138;124;87;86;103;113;90;89;88;86 15:32:30 INFO - PROCESS | 1664 | |8;json-parse-financial;57;55;55;57;58;60;59;59;60;59 15:32:30 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;75;69;82;115;121;75;73;74;73;75 15:32:30 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;87;85;83;85;85;86;85;90;85;87 15:32:30 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;157;160;143;155;166;166;163;145;186;144 15:32:30 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;168;175;168;233;168;167;169;168;170;167 15:32:30 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;59;68;88;70;67;68;67;70;67;68 15:32:30 INFO - PROCESS | 1664 | __end_tp_report 15:32:30 INFO - PROCESS | 1664 | __start_cc_report 15:32:30 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,960 15:32:30 INFO - PROCESS | 1664 | __end_cc_report 15:32:30 INFO - PROCESS | 1664 | __startTimestamp1484436750707__endTimestamp 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:32:30 INFO - PROCESS | 1664 | Number of tests: 14 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:118.30 Median:118.50 stddev:4.60 (3.9%) stddev-sans-first:2.46 15:32:30 INFO - PROCESS | 1664 | Values: 107.0 118.0 118.0 120.0 118.0 118.0 125.0 119.0 118.0 122.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:115.50 stddev:14.88 (12.9%) stddev-sans-first:13.01 15:32:30 INFO - PROCESS | 1664 | Values: 146.0 113.0 112.0 144.0 115.0 112.0 110.0 112.0 140.0 116.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:211.20 Median:199.00 stddev:31.42 (15.8%) stddev-sans-first:32.90 15:32:30 INFO - PROCESS | 1664 | Values: 197.0 199.0 199.0 197.0 196.0 193.0 196.0 200.0 289.0 246.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:75.70 Median:63.50 stddev:19.81 (31.2%) stddev-sans-first:20.99 15:32:30 INFO - PROCESS | 1664 | Values: 73.0 59.0 63.0 116.0 84.0 59.0 64.0 78.0 102.0 59.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:74.50 stddev:17.82 (23.9%) stddev-sans-first:18.77 15:32:30 INFO - PROCESS | 1664 | Values: 82.0 78.0 125.0 105.0 102.0 71.0 87.0 71.0 71.0 88.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:105.10 Median:75.50 stddev:27.62 (36.6%) stddev-sans-first:27.36 15:32:30 INFO - PROCESS | 1664 | Values: 77.0 96.0 77.0 111.0 135.0 78.0 136.0 74.0 135.0 132.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:95.50 stddev:18.21 (19.1%) stddev-sans-first:19.25 15:32:30 INFO - PROCESS | 1664 | Values: 98.0 95.0 96.0 106.0 153.0 94.0 96.0 95.0 96.0 93.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:100.40 Median:86.50 stddev:18.63 (21.5%) stddev-sans-first:13.93 15:32:30 INFO - PROCESS | 1664 | Values: 138.0 124.0 87.0 86.0 103.0 113.0 90.0 89.0 88.0 86.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:59.00 stddev:1.85 (3.1%) stddev-sans-first:1.94 15:32:30 INFO - PROCESS | 1664 | Values: 57.0 55.0 55.0 57.0 58.0 60.0 59.0 59.0 60.0 59.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:83.20 Median:74.50 stddev:18.67 (25.1%) stddev-sans-first:19.57 15:32:30 INFO - PROCESS | 1664 | Values: 75.0 69.0 82.0 115.0 121.0 75.0 73.0 74.0 73.0 75.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.50 stddev:1.87 (2.2%) stddev-sans-first:1.94 15:32:30 INFO - PROCESS | 1664 | Values: 87.0 85.0 83.0 85.0 85.0 86.0 85.0 90.0 85.0 87.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:161.50 stddev:13.07 (8.1%) stddev-sans-first:13.86 15:32:30 INFO - PROCESS | 1664 | Values: 157.0 160.0 143.0 155.0 166.0 166.0 163.0 145.0 186.0 144.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:168.50 stddev:20.41 (12.1%) stddev-sans-first:21.47 15:32:30 INFO - PROCESS | 1664 | Values: 168.0 175.0 168.0 233.0 168.0 167.0 169.0 168.0 170.0 167.0 15:32:30 INFO - PROCESS | 1664 | 15:32:30 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:68.00 stddev:7.28 (10.7%) stddev-sans-first:6.73 15:32:30 INFO - PROCESS | 1664 | Values: 59.0 68.0 88.0 70.0 67.0 68.0 67.0 70.0 67.0 68.0 15:32:30 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:32:30 INFO - PROCESS | 1664 | 15:32:31 INFO - TEST-INFO | 1664: exit 0 15:32:31 INFO - TEST-OK | kraken | took 60828ms 15:32:31 INFO - SUITE-END | took 1176s 15:32:31 INFO - Completed test suite (00:19:36) 15:32:31 INFO - javascript benchmark 15:32:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7077.664281591084, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.100062982996, 3350.3299340131966, 3373.2747459296247, 3389.1527118220447, 3390.288039511702, 5624.521915637166, 5646.915296270554, 5666.57500687448, 5680.0, 5713.885722285562, 5601.29983752031, 5649.49154576088, 5653.236813030239, 5668.57485688574, 5668.858278543028, 5115.4884511548735, 5123.0, 5158.767855446496, 5219.0, 5245.449227831061, 5208.0365132450615, 5226.451222621627, 5259.710715910624, 5260.526552610264, 5291.973540132294, 4798.640101992364, 5059.342285502861, 5103.413107492649, 5180.45605211453, 5217.765200565984, 5061.190209566452, 5126.128558145124, 5128.076946149691, 5130.820421285274, 5136.589072874199, 3982.880513584597, 4221.345691417835, 5064.620153488503, 5182.611304152204, 5190.169572868361, 5534.418886016951, 5671.7164141793155, 5677.574181936371, 5687.40282270364, 5691.51622112121, 6162.815115546541, 6177.474914632262, 6189.102580125901, 6202.751889924398, 6236.064590311445, 6222.751089956397, 6268.153799237136, 6271.090691849657, 6279.7174127163935, 6302.086197501382, 5632.295963004624, 5645.633033852787, 5815.79644712433, 5827.184194212814, 5844.094165404369, 5343.305370301836, 5568.192612071227, 5972.835297117021, 6243.313235544087, 6247.437730604267, 5162.793488260428, 5801.868635616099, 6097.573169878155, 6113.23584551931, 6126.448619624255, 5736.196932429463, 6040.305364883006, 6232.0, 6246.281677607042, 6266.185395898504, 5908.084246941731, 5934.970325148433, 6055.515558755289, 6066.787662431793, 6130.049842274455, 5986.880262394728, 5989.580729348903, 6067.939320606737, 6068.33248342682, 6144.41628045335, 4829.44461386945, 4839.516048395132, 4839.9758001210475, 4847.612191024709, 4854.199057155601, 4300.053988122608, 4335.002949321673, 4342.0, 4349.28236840924, 4355.825766969286, 4872.415310162733, 5595.440455954372, 5738.454846789548, 5760.596758226968, 5829.09649004405, 6130.29501607312, 6249.0938813871735, 6267.185265915402, 6274.905876411857, 6281.59169653971, 5631.324241091095, 5637.46444087811, 5686.175504551817, 5689.203511508392, 5744.109663002241, 5306.097963346163, 5322.813701520428, 5328.653637513627, 5332.626716129911, 5346.385165705994, 6213.751449941951, 6254.249490061222, 6260.655663938527, 6269.247690277196, 6302.369763023661, 6106.664133472702, 6112.351504965139, 6156.815295541141, 6157.322694503511, 6215.9378406215355, 6257.5932564383165, 6277.18396608438, 6279.0, 6288.779892703734, 6328.746850125944, 6127.785527506516, 6146.877062458815, 6152.507799376129, 6171.59884607499, 6187.948048831619, 4902.877428064185, 5987.43119403656, 6112.388761123852, 6121.666376154927, 6228.0, 5975.671338076448, 6103.511719062553, 6124.8775024500155, 6135.569531795875, 6177.660228687465, 5371.731413429391, 6090.390960903874, 6096.533431718184, 6110.4980065817745, 6138.416850399205, 4283.485981682218, 5846.41535846412, 5927.525797936069, 5963.73163207648, 6015.57890947647, 5945.227120474311, 5971.850703732442, 5984.760609575568, 6034.758609655564, 6066.605670631571, 5145.794168233228, 5905.557083218861, 5916.112583112395, 5929.822105336847, 5946.07835785454], "value": 5593.802535848635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.78202244944885, 501.9648624596275, 505.8330750852219, 519.7115600841531, 565.5277843001093, 36599.63400365996, 38481.0, 38587.807060964726, 38603.613963860356, 38603.99999999996, 39400.0, 39589.8123056308, 39652.206955860864, 39961.80019099908, 40044.0, 9232.6306947722, 9266.814663706706, 9384.718458446258, 9401.435913845156, 9416.952915235413, 8870.556472176399, 8943.373963822534, 8982.146696063863, 9047.140521650434, 9050.637974481013, 8936.329775266848, 9004.279657627374, 9037.864432033492, 9067.183953444188, 9097.044810294923, 8976.685815996409, 8984.595693193789, 9033.187013168781, 9059.68291109808, 9075.773105672411, 8238.846561481398, 9173.174414302677, 9358.11097945694, 9367.718968430958, 9427.292953028551, 37834.43248351277, 37892.86321410362, 37955.05112372185, 38151.61848381509, 38157.0, 3279.1638132276357, 3398.9660103398896, 3504.1590018395655, 3816.46569480273, 3978.0452691353867, 28264.576031359546, 29453.116406507837, 29695.703042969726, 30049.248768780737, 30202.54696179559, 3173.174974506645, 3191.6329622093526, 3198.616166060064, 3204.96795032049, 3220.951685724716, 517298.0, 517589.0, 519346.0, 519614.0, 520753.0, 48930.51069489331, 53079.0, 56114.719426402604, 56359.43640563542, 56449.71775141098, 56992.43007569954, 57488.0, 57830.42169578335, 57881.0, 57924.420755792744, 15823.841761582467, 15953.840461595235, 16040.919795401182, 16044.919775401282, 16071.0, 12272.32502212369, 12578.0, 12619.179753316004, 12699.174553653984, 12719.0, 14301.642458938402, 14423.0, 14668.339924703414, 14694.971352005257, 14823.25883705832, 6355.904661430082, 6482.481401487869, 6529.967350163219, 6570.145881035531, 6579.901301480481, 827.5407149032345, 928.8095940332215, 939.3377668743519, 943.1370296178919, 998.6205242007991, 29087.41825163485, 29298.868371660206, 29537.852310738308, 29715.85142074319, 29857.402851943272, 1055.0873494427265, 1062.7555662197738, 1078.7087486378634, 1085.1427372375736, 1147.6958605969257, 12310.446029928675, 13256.337183140804, 14413.774829139431, 15138.924305378403, 15316.923415382851, 659150.0, 660343.0, 665133.0, 666405.0, 668629.0, 45886.31170532445, 48000.79998000008, 54946.0, 55446.89106217853, 55568.72215638977, 1327.6879933215687, 1354.674878029266, 1368.5278578890268, 1369.137443410645, 1375.4498200719793, 28904.0, 29296.707032929397, 29565.556516651835, 30030.69969300279, 30160.24599385033, 47030.29454558116, 47726.284105739134, 47828.0, 48178.518214817406, 48236.03527929492, 631728.0, 633952.0, 634723.0, 637304.0, 637718.0, 25651.10221142251, 25667.486650266517, 25896.741032589434, 26675.466490670467, 27164.1850744478, 45058.09883802371, 45200.095998079196, 45470.0, 45587.77206113948, 45726.77136614296, 36921.0, 37347.62652373441, 37740.81129594334, 38403.807980961035, 38809.41785873158, 37483.43774843325, 37528.81235593805, 38009.239815202985, 38377.0, 38930.8320750378, 35580.28839423249, 36161.0, 36726.08184795402, 36977.815110924275, 37234.813825930694, 32612.021639350856, 33628.32743345168, 33988.83005584956, 33997.3200535983, 34219.82890085533, 25282.873585631954, 25334.74665253324, 25483.872580636977, 25580.616290755283, 25777.871110644326], "value": 19279.89155134388, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.62348663443476, 368.40686494743477, 380.05935310107486, 382.6020938224247, 397.2313573235789, 18651.507879369652, 19124.0, 19340.709889351634, 19875.900620496894, 20180.596388072267, 9881.45651989142, 11035.558577656904, 11424.200305978586, 11875.940620296908, 11897.58358457452, 4134.710570260083, 5849.795257165979, 5911.9113213301835, 5922.496587790043, 5932.762689492415, 3848.1918797052654, 4070.715049946505, 5818.447247511501, 6294.748210071614, 6587.044878492616, 6274.968625156891, 6612.173478315211, 6660.633665148439, 6672.933270667279, 6675.2323482799375, 5938.64368137911, 5968.820935371924, 6065.787697430613, 6069.180660610805, 6079.361667024965, 5727.713614319267, 5794.159846822229, 5851.824445266649, 5892.587518873681, 5919.970400148015, 13163.802542961863, 13217.537386191438, 13245.735085298336, 13315.134516256412, 21569.1372345106, 2194.4513871532117, 2205.9558808823735, 2292.988535057314, 2370.549595576852, 2478.0, 9562.952185239099, 9780.706578802647, 10070.244731645156, 10079.596816127347, 10280.640177593748, 1774.1218097042047, 1854.2953677602425, 1855.8422534084484, 1870.2892900697784, 1936.5836345185853, 110609.34085988636, 115475.42262288718, 116214.0, 122331.0, 122703.386483067, 9228.8615670765, 11262.155338349656, 11379.317240965569, 11543.480543375568, 11686.065114790796, 14294.0, 14575.41698332076, 14581.76055035334, 14616.0, 14635.926820366043, 8222.095569487352, 8458.577071146541, 8516.361272904433, 8550.144985501525, 8645.913540864636, 8275.50346979183, 8418.368622353226, 8466.280366168821, 8562.229399353964, 8580.528070956158, 4381.364702118175, 4429.579189976947, 4479.372887795711, 4512.9097418052115, 4623.583877451046, 1696.5334533003522, 1709.8116808817751, 1821.4080423862297, 1903.7625543396903, 1910.4650697804636, 753.6947536247719, 860.2859626509982, 871.4335681806876, 871.4814685262259, 954.3415043620018, 15362.462313818962, 16593.91703041477, 16693.415730449375, 19535.316263930694, 20476.69284960727, 897.1521911793293, 897.304588943574, 937.128470522407, 943.2642538819731, 974.0357046523891, 10334.896651033543, 10615.84076238857, 10699.411532365639, 10716.196285278635, 10736.355818650905, 512346.0, 538706.0, 551236.0, 558036.0, 561464.0, 102265.0, 122248.0, 124490.13264801035, 130003.34998324949, 131560.3421982884, 930.8789857318505, 941.3551717073826, 950.3395140377371, 950.5722424908819, 954.7804005078732, 16805.41181058706, 17498.562535936704, 18016.099195040457, 18067.728984065514, 18123.275068997093, 43058.78470607627, 51002.0, 51467.74266128645, 52618.473815262885, 53077.7346113267, 74660.0, 76604.616976917, 76847.0, 76961.61519192593, 77083.0, 7838.529688218725, 7895.210478952288, 7954.403419743659, 8081.878771818545, 8126.309263712651, 11802.527898884291, 11955.103367247317, 12329.260244385365, 12392.62822115338, 12686.175398599062, 5138.698282311389, 5305.840824775263, 10508.684739457829, 10791.460426978776, 10970.451477425937, 10623.203259755395, 10781.299215550964, 10865.07646849995, 10917.83623245636, 11117.722056948642, 10911.127109831354, 11016.17378696617, 11065.668029959113, 11188.776224475629, 11420.200585959235, 7899.960500197462, 7970.083440404428, 8117.918820811952, 8210.876836847332, 8218.301444377295, 10555.419451930218, 11051.0, 11127.1654625905, 11187.608433704781, 11236.98867101932], "value": 9002.396497911484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.7438025204833, 1443.0043270143603, 1584.3266611690028, 1776.2184638758954, 1782.37616834108, 7525.059367579052, 7786.377089832814, 7989.8402031959395, 8492.53291068991, 8510.617022234006, 7916.050073991115, 8250.628721707522, 8268.0, 8322.167783221676, 8340.3744719146, 2318.0611852199845, 2356.905723771047, 2366.668666386707, 2369.18263199196, 2379.3694670912223, 1970.9605807883834, 2345.8944347504316, 2369.2655276864143, 2376.738558758535, 2399.8200134989856, 1896.7534220551308, 1925.4608709561346, 2333.94165145871, 2349.5653304138705, 2359.6696462495265, 2235.564065007324, 2242.8990695418747, 2260.457490202356, 2262.366537369539, 2264.230161745006, 2290.0496294037953, 2343.414146463384, 2354.799842013431, 2359.7522260162777, 2363.4918492524016, 8309.667613295462, 8309.667613295462, 8372.03721572021, 8376.664933402717, 8389.07720150783, 4123.60825721556, 5244.947550524484, 5247.055530004598, 5250.264962905197, 5255.211718242294, 3253.3330667213163, 3333.5333053372306, 3544.9645503544893, 3556.324298383299, 3566.928661426783, 1053.5733028123623, 1063.1335461598771, 1082.815921293382, 1093.4915264402011, 1095.4413249242943, 10838.67483975482, 10866.565337386495, 10901.073408760345, 10930.0, 10956.397398143099, 4527.3435351873795, 4622.375979242759, 4707.388039554904, 4724.102420540086, 4762.880927976759, 9413.623455061721, 9426.387284826464, 9447.527623618791, 9457.76355591102, 9458.243340532741, 5206.505381988705, 5216.40011398694, 5221.477852214824, 5224.085784987612, 5230.947690523122, 4268.615824575803, 4273.423087883096, 4726.385569875894, 4785.090832741768, 4792.0895029944195, 3144.685531446837, 3801.2207497462955, 3878.3406820840523, 3921.0001449630163, 3930.6069393060466, 1197.6227488341144, 1269.2574843716345, 1272.3129510064646, 1276.425608476171, 1276.942537585811, 819.5246756881114, 879.3712495565701, 907.0476000199764, 907.5734404829719, 913.57975331347, 4874.634402419832, 5628.448926118557, 5711.343195532567, 5805.88388232233, 6007.939920600826, 819.9262066414051, 903.8599017152352, 941.7033634405141, 958.6596758150896, 968.080323692495, 3445.1903802606366, 3774.509313789245, 3775.5846856845724, 3870.40008798631, 3896.473976013259, 8236.423450358538, 9881.407115573089, 9978.550965206583, 10130.341527800669, 10234.846477302846, 9904.653337133166, 10052.597896084075, 10160.949195254125, 10200.38797672142, 10211.029952154533, 7856.135825059239, 7907.327877130393, 7909.920900791033, 7918.247766462292, 8029.879551806727, 4502.414686090919, 4522.412086428743, 5479.780808767605, 5519.31008623922, 5732.484076433141, 9284.442933424016, 9307.11582399671, 9341.532923353941, 9518.428894266366, 9544.713658590254, 9466.668666596635, 9520.523973801144, 9530.046995300414, 9620.13418792312, 9729.270304727032, 6597.60414375139, 6769.458443324621, 6855.931440685529, 6863.656817159222, 6874.8625027500175, 7842.098158711821, 7869.960650196713, 7918.0, 7926.920730792618, 7934.444588878723, 7145.428365730625, 7171.354578087997, 7199.532030418006, 7313.597752123685, 7378.114626244885, 6932.0988271524375, 7054.717811287491, 7118.430525558047, 7132.1441427029085, 7138.85722285541, 6634.436072933855, 6746.561473504206, 6762.830929226809, 6767.560108593123, 6778.0, 4854.854354369375, 4870.829520966749, 4986.9251961221335, 5012.573931215888, 5054.4187418447345, 4939.60483161339, 4972.900541989212, 5048.167052436307, 5080.161773307363, 5090.720010399464], "value": 4406.573178238698, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.41935483870964, 305.7355387589736, 308.76071044940176, 315.7474020783374, 316.7260319823351, 5189.247559103927, 5431.945680543194, 5471.945280547194, 5548.0, 5600.551955843533, 4546.45442546894, 4729.739864307462, 5267.894642107156, 5502.58730595206, 5524.287470884029, 4180.912962629715, 4185.414042034103, 4206.053637931456, 4216.406909750497, 4218.578142185772, 3429.159855835324, 3990.4014397840265, 4143.295639741251, 4226.3517228281, 4235.555266696983, 3405.829708514577, 4115.950432639687, 4118.320477121287, 4138.654937145425, 4157.812898419564, 4121.010957370225, 4146.626803587692, 4149.2738770715005, 4168.291390463629, 4180.728252663567, 3046.6039414875922, 3537.9469307960403, 3597.5143355646915, 3598.7300952428673, 3604.6936010439144, 4354.956450435487, 4629.125204292267, 5065.746712664352, 5368.0, 5437.673739575598, 3843.693144330941, 4163.458750362463, 4753.78607962639, 4761.523847615245, 4792.496787837296, 5101.69389836611, 5821.417858214187, 5865.325487568942, 5885.146653735227, 5904.645721256696, 3504.759241318008, 3812.3137835189655, 3897.337452633059, 3942.448057271984, 3957.3668213086057, 5810.970945145289, 5958.152972578471, 5969.373215812364, 6096.7561297548045, 6213.5339849511465, 3420.709239714652, 3941.0935484838074, 4005.1989602079702, 4008.8396464141106, 4012.558618551957, 3920.4511368408444, 3991.880243592697, 4017.754496106158, 4044.492414702844, 4073.7148399612333, 3925.2738243424487, 4128.876133715993, 4314.913701725948, 4605.539446055434, 4710.929336059962, 5863.3257175424515, 5881.118042226532, 5903.970480147657, 5918.763249470059, 5950.7917222896995, 1061.946902654864, 1250.2685928731612, 1267.9112462127562, 1300.4928078049568, 1322.623052430072, 748.3938010211746, 842.6208206307186, 850.9319254459753, 857.9442336248123, 876.3646356391565, 4856.028794241166, 5020.924686129711, 5069.062223488666, 5147.922781158286, 5246.291750613695, 5025.346704928336, 5667.299810056967, 5670.121131224667, 5755.65466072037, 5787.39567194764, 4492.3710680504755, 4497.550244975541, 4906.362172917569, 5196.298499702491, 5244.868878278074, 2669.933251668685, 2726.428378823747, 2883.78371622125, 3058.709422604849, 3097.38052389523, 5433.565314774809, 5547.528460080857, 5906.0255057914965, 6077.544184186204, 6097.847553811191, 5121.0, 5156.974215128976, 5513.250989327215, 5546.223528705985, 5740.282464691913, 4933.851984440545, 5757.769689212385, 5819.709014549256, 6000.649529091374, 6041.848953776191, 4693.044974195134, 5825.3592104867585, 5829.0, 5836.182934389274, 5868.67722275279, 4778.116048531033, 4824.517548245148, 4982.6512144149565, 5682.0, 5696.148751655811, 5598.3128098512125, 5771.422857714195, 5937.910931335947, 6060.181875446935, 6076.817695469143, 2768.6400767900045, 3388.678075582816, 3786.659200671953, 3796.240751849696, 3812.618738126165, 5443.074677304789, 5528.751206195811, 5566.415526369673, 5835.424435402502, 6057.394260573908, 5050.84847454577, 5731.627444216113, 5827.737751801264, 5917.0, 5932.436418540232, 5074.31496747949, 5229.692576855786, 5931.228940237742, 5966.582339236212, 5984.307061822285, 5469.371022332482, 5735.426457354231, 5762.2221000164445, 5827.94172058274, 5854.736536855937, 5573.359063707726, 5578.386377498553, 5982.850428739316, 5985.820425387245, 5987.910181347371, 4819.855404337875, 5523.309586301712, 5853.078437447006, 5855.268091488564, 5887.146363777143], "value": 4128.70141376178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.5771400588638, 745.1468069060926, 745.455817253405, 781.3631890009639, 788.5426452657459, 13562.864371356283, 13934.51229206978, 14151.92924035381, 14197.574072777834, 14212.147271163725, 14024.719505609908, 14617.196054217015, 14624.634384140376, 14900.254987250648, 14915.254237288147, 636.1348565950304, 651.986161518838, 774.577855068986, 776.2121446731586, 799.5322736199302, 779.2558107007823, 783.9921600783977, 797.6450479536609, 798.389232237336, 802.1096582793095, 838.2707044870971, 839.2698352433391, 842.5323945210406, 844.0968164064453, 844.9281811046069, 801.7795106345773, 833.829065041671, 839.1566475691922, 840.36552402936, 841.1210285251896, 600.1627729317578, 625.4933503861887, 626.2703949898363, 627.4509803921569, 628.2649300318639, 13925.791113133311, 13943.16341019542, 14049.78925316121, 14051.578452646438, 14268.643283917881, 13087.607371778862, 13359.53241636538, 13394.263315517643, 13395.665108372272, 13600.65998350049, 11293.548258069668, 11356.48895799691, 11358.886411135949, 11574.482423713876, 11865.40672966357, 3555.3600351936643, 3612.3858943979585, 3677.1358730698266, 3693.4644476550748, 3696.482492451059, 57050.0, 83194.58402707947, 86679.0, 88373.0, 89046.21907561878, 10111.090001899716, 10193.949030254802, 10268.229882758824, 10393.58425662965, 10476.842847357295, 19901.800981990284, 19949.202031918852, 19968.0, 20286.884221367967, 20288.898555507127, 7492.70029198826, 7502.099748030271, 7521.135069466972, 7584.924150758422, 7718.691252349843, 4013.237484877864, 4201.474815648044, 4281.957180428218, 4333.609975102256, 4347.630451411602, 2672.4521473098357, 3337.8497967591516, 3444.190615205425, 3446.379651662725, 3465.480177973274, 632.1434456311723, 644.690548536705, 647.1974751308344, 649.7530938243438, 664.64773669955, 571.5941681406248, 572.221778381401, 582.3186871360518, 625.2247213455373, 631.3528633151057, 8606.698765543177, 8777.253933415732, 9067.54662266884, 9092.181703646704, 9111.134442227978, 552.1165292983554, 609.8108688058215, 612.2163630552899, 641.9935800642028, 662.8309781005813, 4063.7764922929523, 4092.672586193157, 4396.538363471854, 4439.933400998988, 4523.864284071483, 79242.0, 108678.0, 111018.44490777495, 116778.0, 124420.1336979946, 95433.0456695438, 96810.0, 98569.0, 100263.49868250612, 101423.0, 54377.3686789389, 69966.30033699598, 72955.63522182356, 89608.10391896128, 89623.55188224147, 8196.56607440998, 9183.908160918305, 9562.56968436436, 9601.615935362786, 9760.365576237522, 41603.58396416118, 49227.507724922296, 51500.0, 53721.0, 54061.03005660515, 58457.0, 71948.0, 75001.0, 75351.24648753442, 77484.61257693675, 12046.75906481883, 12063.698407539881, 12177.208481448677, 12266.9283611384, 12361.134720569475, 13108.279044652254, 13511.639425458148, 13514.729705406033, 13689.794653080013, 14031.579052628438, 12485.31330776816, 12649.873501265238, 12992.415341309475, 13010.219386836441, 13567.660808479866, 12898.806517902427, 12965.0, 12977.0, 13239.3380330985, 13253.807157355885, 10496.317739346918, 12467.127301088836, 12516.87483125157, 12549.435275412447, 12957.611271661865, 8084.474509156886, 9074.274058075207, 9178.908210917805, 10748.366650170758, 11072.335659860435, 10507.842382364424, 11113.0, 11379.146564007568, 11404.94297528507, 11410.714732131763], "value": 7115.582011478074, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 120.0, 118.0, 118.0, 125.0, 119.0, 118.0, 122.0], "value": 118.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 112.0, 144.0, 115.0, 112.0, 110.0, 112.0, 140.0, 116.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 199.0, 197.0, 196.0, 193.0, 196.0, 200.0, 289.0, 246.0], "value": 211.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 59.0, 63.0, 116.0, 84.0, 59.0, 64.0, 78.0, 102.0, 59.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 125.0, 105.0, 102.0, 71.0, 87.0, 71.0, 71.0, 88.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 96.0, 77.0, 111.0, 135.0, 78.0, 136.0, 74.0, 135.0, 132.0], "value": 105.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 96.0, 106.0, 153.0, 94.0, 96.0, 95.0, 96.0, 93.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 124.0, 87.0, 86.0, 103.0, 113.0, 90.0, 89.0, 88.0, 86.0], "value": 100.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 57.0, 58.0, 60.0, 59.0, 59.0, 60.0, 59.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 69.0, 82.0, 115.0, 121.0, 75.0, 73.0, 74.0, 73.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 85.0, 85.0, 86.0, 85.0, 90.0, 85.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 143.0, 155.0, 166.0, 166.0, 163.0, 145.0, 186.0, 144.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 168.0, 233.0, 168.0, 167.0, 169.0, 168.0, 170.0, 167.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 88.0, 70.0, 67.0, 68.0, 67.0, 70.0, 67.0, 68.0], "value": 69.2, "unit": "score"}]}]} 15:32:31 INFO - Return code: 0 15:32:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:32:32 ERROR - # TBPL SUCCESS # 15:32:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:32 INFO - 2017-01-14 15:32:32.018 screenresolution[1669:16686] starting screenresolution argv=screenresolution get 15:32:32 INFO - 2017-01-14 15:32:32.021 screenresolution[1669:16686] Display 0: 1600x1200x32@60 15:32:32 INFO - 2017-01-14 15:32:32.027 screenresolution[1670:16689] starting screenresolution argv=screenresolution list 15:32:32 INFO - Available Modes on Display 0 15:32:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:32 INFO - Intel Iris: 15:32:32 INFO - Chipset Model: Intel Iris 15:32:32 INFO - Type: GPU 15:32:32 INFO - Bus: Built-In 15:32:32 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:32 INFO - Vendor: Intel (0x8086) 15:32:32 INFO - Device ID: 0x0a2e 15:32:32 INFO - Revision ID: 0x0009 15:32:32 INFO - Displays: 15:32:32 INFO - Display: 15:32:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:32 INFO - Display Serial Number: 1600x1200 60 15:32:32 INFO - Main Display: Yes 15:32:32 INFO - Mirror: Off 15:32:32 INFO - Online: Yes 15:32:32 INFO - Rotation: Supported 15:32:32 INFO - Return code: 0 15:32:32 INFO - Running post-action listener: _resource_record_post_action 15:32:32 INFO - [mozharness: 2017-01-14 23:32:32.274634Z] Finished run-tests step (success) 15:32:32 INFO - Running post-run listener: _resource_record_post_run 15:32:32 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32371200; Write bytes: 660613632; Read time: 326; Write time: 3163 15:32:32 INFO - TinderboxPrint: CPU usage
54.2% 15:32:32 INFO - TinderboxPrint: I/O read bytes / time
32,371,200 / 326 15:32:32 INFO - TinderboxPrint: I/O write bytes / time
660,613,632 / 3,163 15:32:32 INFO - TinderboxPrint: CPU idle
2,212.2 (45.8%) 15:32:32 INFO - TinderboxPrint: CPU system
976.1 (20.2%) 15:32:32 INFO - TinderboxPrint: CPU user
1,645.4 (34.0%) 15:32:32 INFO - TinderboxPrint: Swap in / out
377,536,512 / 0 15:32:32 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346518016; Write bytes: 347846656; Read time: 26730; Write time: 702 15:32:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30220800; Write bytes: 305590784; Read time: 299; Write time: 2432 15:32:32 INFO - Running post-run listener: _upload_blobber_files 15:32:32 INFO - Blob upload gear active. 15:32:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:32 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 15:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:32:33 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:32:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:32:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:33 INFO - (blobuploader) - INFO - Done attempting. 15:32:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:32:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:34 INFO - (blobuploader) - INFO - Done attempting. 15:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:37 INFO - (blobuploader) - INFO - Done attempting. 15:32:37 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:37 INFO - Return code: 0 15:32:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f955a92cb7f8a7c5723a577e7b0975f3770b5c0ea90fd21440b434718cae900a8ca0f560c20eebb16873de4a694691b2654028aa963765134d09ef8669de8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/020b62369a2fdcdc5d7d2971614a6c0ddee20e25d4d1b63f0e3ea7a7b652fc43934bb39a3ff22c9b6c1bc3006160e9734bce4de2363e4e39cd4d9b42beab3236", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/872a2f27b0ee7e6248cc6dec832e950a780c32784a5b296f9523cd86c8cc908c4f66a31e43c97cbba4d34467cfd7e5ae98cbc78d534282018a0dac0c575689f6"} 15:32:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:37 INFO - Contents: 15:32:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f955a92cb7f8a7c5723a577e7b0975f3770b5c0ea90fd21440b434718cae900a8ca0f560c20eebb16873de4a694691b2654028aa963765134d09ef8669de8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/020b62369a2fdcdc5d7d2971614a6c0ddee20e25d4d1b63f0e3ea7a7b652fc43934bb39a3ff22c9b6c1bc3006160e9734bce4de2363e4e39cd4d9b42beab3236", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/872a2f27b0ee7e6248cc6dec832e950a780c32784a5b296f9523cd86c8cc908c4f66a31e43c97cbba4d34467cfd7e5ae98cbc78d534282018a0dac0c575689f6"} 15:32:37 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:37 INFO - Copying logs to upload dir... 15:32:37 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.532077 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-14 15:32:37.411773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:37.415267) ========= 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.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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/Mozilla-Aurora/sha512/93f955a92cb7f8a7c5723a577e7b0975f3770b5c0ea90fd21440b434718cae900a8ca0f560c20eebb16873de4a694691b2654028aa963765134d09ef8669de8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/020b62369a2fdcdc5d7d2971614a6c0ddee20e25d4d1b63f0e3ea7a7b652fc43934bb39a3ff22c9b6c1bc3006160e9734bce4de2363e4e39cd4d9b42beab3236", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/872a2f27b0ee7e6248cc6dec832e950a780c32784a5b296f9523cd86c8cc908c4f66a31e43c97cbba4d34467cfd7e5ae98cbc78d534282018a0dac0c575689f6"} build_url:https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010932 build_url: 'https://queue.taskcluster.net/v1/task/Hfp6dUyzTAusTTR70F4CgA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f955a92cb7f8a7c5723a577e7b0975f3770b5c0ea90fd21440b434718cae900a8ca0f560c20eebb16873de4a694691b2654028aa963765134d09ef8669de8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/020b62369a2fdcdc5d7d2971614a6c0ddee20e25d4d1b63f0e3ea7a7b652fc43934bb39a3ff22c9b6c1bc3006160e9734bce4de2363e4e39cd4d9b42beab3236", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/872a2f27b0ee7e6248cc6dec832e950a780c32784a5b296f9523cd86c8cc908c4f66a31e43c97cbba4d34467cfd7e5ae98cbc78d534282018a0dac0c575689f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:37.448453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:37.448754) ========= 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.guK6pG9rwN/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.61DtbK6kAn/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.005181 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 15:32:37.497417) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 15:32:37.497719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 15:32:37.498038) ========= ========= Total master_lag: 0.18 =========