builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1483021674.8 results: success (0) buildid: 20161229041337 builduid: f3572429ac8a4049a90aa427f8c96371 revision: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:54.799801) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:54.800249) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:54.800548) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:54.850611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:54.851012) ========= 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.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-29 06:27:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.02M=0.001s 2016-12-29 06:27:55 (8.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.504388 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.378616) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.378940) ========= 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.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.021412 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.433753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.434112) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 --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 bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-29 06:27:55,513 truncating revision to first 12 chars 2016-12-29 06:27:55,513 Setting DEBUG logging. 2016-12-29 06:27:55,513 attempt 1/10 2016-12-29 06:27:55,513 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bf6b75460a25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 06:27:56,154 unpacking tar archive at: mozilla-aurora-bf6b75460a25/testing/mozharness/ program finished with exit code 0 elapsedTime=0.893063 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.351150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.351482) ========= script_repo_revision: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.351916) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.352196) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.372223) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-29 06:27:56.372525) ========= /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.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:27:56 INFO - MultiFileLogger online at 20161229 06:27:56 in /builds/slave/test 06:27:56 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 06:27:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:27:56 INFO - {'append_to_log': False, 06:27:56 INFO - 'base_work_dir': '/builds/slave/test', 06:27:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:27:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:27:56 INFO - 'branch': 'Mozilla-Aurora', 06:27:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:27:56 INFO - 'config_files': ('talos/mac_config.py',), 06:27:56 INFO - 'default_actions': ('clobber', 06:27:56 INFO - 'read-buildbot-config', 06:27:56 INFO - 'download-and-extract', 06:27:56 INFO - 'populate-webroot', 06:27:56 INFO - 'create-virtualenv', 06:27:56 INFO - 'install', 06:27:56 INFO - 'run-tests'), 06:27:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:27:56 INFO - 'download_minidump_stackwalk': True, 06:27:56 INFO - 'download_symbols': 'ondemand', 06:27:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:27:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:27:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:27:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:27:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:27:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:27:56 INFO - 'installer_path': 'installer.exe', 06:27:56 INFO - 'log_level': 'info', 06:27:56 INFO - 'log_name': 'talos', 06:27:56 INFO - 'log_to_console': True, 06:27:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:27:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:27:56 INFO - 'opt_config_files': (), 06:27:56 INFO - 'pip_index': False, 06:27:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:56 INFO - 'cmd': ('bash', 06:27:56 INFO - '-c', 06:27:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:56 INFO - 'enabled': True, 06:27:56 INFO - 'halt_on_failure': False, 06:27:56 INFO - 'name': 'check_screen_resolution'},), 06:27:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:56 INFO - 'cmd': ('bash', 06:27:56 INFO - '-c', 06:27:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:56 INFO - 'enabled': True, 06:27:56 INFO - 'halt_on_failure': False, 06:27:56 INFO - 'name': 'check_screen_resolution'},), 06:27:56 INFO - 'run_cmd_checks_enabled': True, 06:27:56 INFO - 'sps_profile': False, 06:27:56 INFO - 'sps_profile_interval': 0, 06:27:56 INFO - 'suite': 'dromaeojs-e10s', 06:27:56 INFO - 'system_bits': '32', 06:27:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:27:56 INFO - 'title': 't-yosemite-r7-0068', 06:27:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:27:56 INFO - 'use_talos_json': True, 06:27:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:27:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:27:56 INFO - 'work_dir': 'build'} 06:27:56 INFO - [mozharness: 2016-12-29 14:27:56.578117Z] Running clobber step. 06:27:56 INFO - Running pre-action listener: _resource_record_pre_action 06:27:56 INFO - Running main action method: clobber 06:27:56 INFO - rmtree: /builds/slave/test/build 06:27:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:27:58 INFO - Running post-action listener: _resource_record_post_action 06:27:58 INFO - [mozharness: 2016-12-29 14:27:58.093904Z] Finished clobber step (success) 06:27:58 INFO - [mozharness: 2016-12-29 14:27:58.093979Z] Running read-buildbot-config step. 06:27:58 INFO - Running pre-action listener: _resource_record_pre_action 06:27:58 INFO - Running main action method: read_buildbot_config 06:27:58 INFO - Using buildbot properties: 06:27:58 INFO - { 06:27:58 INFO - "project": "", 06:27:58 INFO - "product": "firefox", 06:27:58 INFO - "script_repo_revision": "production", 06:27:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 06:27:58 INFO - "repository": "", 06:27:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 06:27:58 INFO - "buildid": "20161229041337", 06:27:58 INFO - "pgo_build": "False", 06:27:58 INFO - "basedir": "/builds/slave/test", 06:27:58 INFO - "buildnumber": 23, 06:27:58 INFO - "slavename": "t-yosemite-r7-0068", 06:27:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:27:58 INFO - "platform": "yosemite_r7", 06:27:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:27:58 INFO - "branch": "mozilla-aurora", 06:27:58 INFO - "repo_path": "releases/mozilla-aurora", 06:27:58 INFO - "revision": "bf6b75460a2544ee06f6331cb6ed0a0da32cddf1", 06:27:58 INFO - "stage_platform": "macosx64", 06:27:58 INFO - "builduid": "f3572429ac8a4049a90aa427f8c96371", 06:27:58 INFO - "slavebuilddir": "test" 06:27:58 INFO - } 06:27:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 06:27:58 INFO - Running post-action listener: _resource_record_post_action 06:27:58 INFO - [mozharness: 2016-12-29 14:27:58.097341Z] Finished read-buildbot-config step (success) 06:27:58 INFO - [mozharness: 2016-12-29 14:27:58.097451Z] Running download-and-extract step. 06:27:58 INFO - Running pre-action listener: _resource_record_pre_action 06:27:58 INFO - Running main action method: download_and_extract 06:27:58 INFO - mkdir: /builds/slave/test/build/tests 06:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:58 INFO - trying https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 06:27:58 INFO - Downloading https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/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 06:27:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/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 06:27:59 INFO - Downloaded 1199 bytes. 06:27:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 06:27:59 INFO - Using the following test package requirements: 06:27:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 06:27:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 06:27:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 06:27:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'jsshell-mac.zip'], 06:27:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 06:27:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 06:27:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 06:27:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 06:27:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 06:27:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:27:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 06:27:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:27:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 06:27:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:27:59 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 06:27:59 INFO - Http code: 200 06:27:59 INFO - via: 1.1 4a7b695b8ae560fe9087da065a2b7812.cloudfront.net (CloudFront) 06:27:59 INFO - x-amz-cf-id: oKMd7RWD1Ky0iRK3jfpzoQYeUc1BHcvNusneGMLgYPSEau12csqi7Q== 06:27:59 INFO - x-amz-version-id: 39oTqBORJmIwa1Ki1Bw6SepkbrPEalW3 06:28:00 INFO - Expected file size: 16268460 06:28:00 INFO - Obtained file size: 16268460 06:28:07 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 06:28:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 06:28:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:28:07 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 06:28:07 INFO - Http code: 200 06:28:07 INFO - via: 1.1 0576b942ae9f4fc9c0b62b0736e9bfd6.cloudfront.net (CloudFront) 06:28:07 INFO - x-amz-cf-id: l6A42PvDr2-Hq7XniKcanco0AGO3KRaWOXE_Hu1XgAWp4VKVdvKLmA== 06:28:07 INFO - x-amz-version-id: 39oTqBORJmIwa1Ki1Bw6SepkbrPEalW3 06:28:07 INFO - Expected file size: 16268460 06:28:07 INFO - Obtained file size: 16268460 06:28:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 06:28:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:28:14 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 06:28:14 INFO - Http code: 200 06:28:14 INFO - via: 1.1 978198446b6fdba8a499c04f84a3a7e6.cloudfront.net (CloudFront) 06:28:14 INFO - x-amz-cf-id: 0v13_sHU72A3cAFxCHcHciKNIhHweGo-wFWmwGgnhp5ddMvVdkrlOQ== 06:28:14 INFO - x-amz-version-id: G4y6QENueRlfoodpRlA_B22EPdCyhL0O 06:28:15 INFO - Expected file size: 14409038 06:28:15 INFO - Obtained file size: 14409038 06:28:15 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')]} 06:28:15 INFO - trying https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:15 INFO - Downloading https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 06:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/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 06:28:18 INFO - Downloaded 109827338 bytes. 06:28:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:18 INFO - mkdir: /builds/slave/test/properties 06:28:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:28:18 INFO - Writing to file /builds/slave/test/properties/build_url 06:28:18 INFO - Contents: 06:28:18 INFO - build_url:https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:19 INFO - Running post-action listener: _resource_record_post_action 06:28:19 INFO - Running post-action listener: set_extra_try_arguments 06:28:19 INFO - [mozharness: 2016-12-29 14:28:19.599120Z] Finished download-and-extract step (success) 06:28:19 INFO - [mozharness: 2016-12-29 14:28:19.599236Z] Running populate-webroot step. 06:28:19 INFO - Running pre-action listener: _resource_record_pre_action 06:28:19 INFO - Running main action method: populate_webroot 06:28:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:28:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:28:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:28:19 INFO - '--tppagecycles', 06:28:19 INFO - '7'], 06:28:19 INFO - 'tests': ['tsvgm']}}, 06:28:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:28:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:28:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:28:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:28:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['damp', 'tps']}, 06:28:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'tests': ['damp', 'tps']}, 06:28:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['dromaeo_dom']}, 06:28:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:28:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['basic_compositor_video']}, 06:28:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:28:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'other-e10s': {'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['a11yr', 06:28:19 INFO - 'ts_paint', 06:28:19 INFO - 'tpaint', 06:28:19 INFO - 'sessionrestore', 06:28:19 INFO - 'sessionrestore_no_auto_restore', 06:28:19 INFO - 'tabpaint']}, 06:28:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['tsvgx', 06:28:19 INFO - 'tsvgr_opacity', 06:28:19 INFO - 'tart', 06:28:19 INFO - 'tscrollx', 06:28:19 INFO - 'cart']}, 06:28:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:28:19 INFO - 'tsvgr_opacity', 06:28:19 INFO - 'tart', 06:28:19 INFO - 'tscrollx', 06:28:19 INFO - 'cart']}, 06:28:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'talos_options': ['--disable-e10s'], 06:28:19 INFO - 'tests': ['tp5o']}, 06:28:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'tests': ['tp5o']}, 06:28:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'talos_options': ['--disable-e10s', 06:28:19 INFO - '--xperf_path', 06:28:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:19 INFO - 'tests': ['tp5n']}, 06:28:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:19 INFO - 'talos_options': ['--xperf_path', 06:28:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:19 INFO - 'tests': ['tp5n']}}, 06:28:19 INFO - 'talos.zip': {'path': '', 06:28:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:28:19 INFO - Running post-action listener: _resource_record_post_action 06:28:19 INFO - [mozharness: 2016-12-29 14:28:19.617954Z] Finished populate-webroot step (success) 06:28:19 INFO - [mozharness: 2016-12-29 14:28:19.618062Z] Running create-virtualenv step. 06:28:19 INFO - Running pre-action listener: _resource_record_pre_action 06:28:19 INFO - Running main action method: create_virtualenv 06:28:19 INFO - Creating virtualenv /builds/slave/test/build/venv 06:28:19 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 06:28:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:28:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:28:19 INFO - Using real prefix '/tools/python27' 06:28:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:28:20 INFO - Installing distribute.............................................................................................................................................................................................done. 06:28:23 INFO - Installing pip.................done. 06:28:23 INFO - Return code: 0 06:28:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:28:23 INFO - Reading from file tmpfile_stdout 06:28:23 INFO - Output received: 06:28:23 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) 06:28:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:28:23 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')]} 06:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:23 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')]} 06:28:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:23 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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:23 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 06:28:23 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 06:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:23 INFO - 'HOME': '/Users/cltbld', 06:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:23 INFO - 'LOGNAME': 'cltbld', 06:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:23 INFO - 'MOZ_NO_REMOTE': '1', 06:28:23 INFO - 'NO_EM_RESTART': '1', 06:28:23 INFO - 'PAGER': '/bin/cat', 06:28:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:23 INFO - 'PWD': '/builds/slave/test', 06:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:23 INFO - 'SHELL': '/bin/bash', 06:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:23 INFO - 'USER': 'cltbld', 06:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:23 INFO - 'XPC_FLAGS': '0x0', 06:28:23 INFO - 'XPC_SERVICE_NAME': '0', 06:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:23 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 06:28:23 INFO - Cleaning up... 06:28:23 INFO - Return code: 0 06:28:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:28:23 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')]} 06:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:23 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')]} 06:28:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:23 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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:23 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 06:28:23 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 06:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:23 INFO - 'HOME': '/Users/cltbld', 06:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:23 INFO - 'LOGNAME': 'cltbld', 06:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:23 INFO - 'MOZ_NO_REMOTE': '1', 06:28:23 INFO - 'NO_EM_RESTART': '1', 06:28:23 INFO - 'PAGER': '/bin/cat', 06:28:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:23 INFO - 'PWD': '/builds/slave/test', 06:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:23 INFO - 'SHELL': '/bin/bash', 06:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:23 INFO - 'USER': 'cltbld', 06:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:23 INFO - 'XPC_FLAGS': '0x0', 06:28:23 INFO - 'XPC_SERVICE_NAME': '0', 06:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:24 INFO - Downloading/unpacking psutil>=3.1.1 06:28:24 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 06:28:24 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 06:28:24 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 06:28:24 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 06:28:24 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 06:28:24 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 06:28:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:28:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:28 INFO - Installing collected packages: psutil 06:28:28 INFO - Running setup.py install for psutil 06:28:28 INFO - building 'psutil._psutil_osx' extension 06:28:28 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 06:28:28 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 06:28:28 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 06:28:28 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 06:28:28 INFO - building 'psutil._psutil_posix' extension 06:28:28 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 06:28:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:28:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:28:28 INFO - ^ 06:28:28 INFO - 1 warning generated. 06:28:28 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 06:28:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:28 INFO - Successfully installed psutil 06:28:28 INFO - Cleaning up... 06:28:28 INFO - Return code: 0 06:28:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:28 INFO - 'HOME': '/Users/cltbld', 06:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:28 INFO - 'LOGNAME': 'cltbld', 06:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:28 INFO - 'MOZ_NO_REMOTE': '1', 06:28:28 INFO - 'NO_EM_RESTART': '1', 06:28:28 INFO - 'PAGER': '/bin/cat', 06:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:28 INFO - 'PWD': '/builds/slave/test', 06:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:28 INFO - 'SHELL': '/bin/bash', 06:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:28 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:28 INFO - 'USER': 'cltbld', 06:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:28 INFO - 'XPC_FLAGS': '0x0', 06:28:28 INFO - 'XPC_SERVICE_NAME': '0', 06:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:28:29 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 06:28:29 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 06:28:29 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 06:28:29 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 06:28:29 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 06:28:29 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 06:28:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:28:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:28:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:28:31 INFO - Installing collected packages: mozsystemmonitor 06:28:31 INFO - Running setup.py install for mozsystemmonitor 06:28:31 INFO - Successfully installed mozsystemmonitor 06:28:31 INFO - Cleaning up... 06:28:32 INFO - Return code: 0 06:28:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:32 INFO - 'HOME': '/Users/cltbld', 06:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:32 INFO - 'LOGNAME': 'cltbld', 06:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:32 INFO - 'MOZ_NO_REMOTE': '1', 06:28:32 INFO - 'NO_EM_RESTART': '1', 06:28:32 INFO - 'PAGER': '/bin/cat', 06:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:32 INFO - 'PWD': '/builds/slave/test', 06:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:32 INFO - 'SHELL': '/bin/bash', 06:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:32 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:32 INFO - 'USER': 'cltbld', 06:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:32 INFO - 'XPC_FLAGS': '0x0', 06:28:32 INFO - 'XPC_SERVICE_NAME': '0', 06:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:32 INFO - Downloading/unpacking jsonschema==2.5.1 06:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:28:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:28:34 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 06:28:34 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 06:28:34 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 06:28:34 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 06:28:34 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 06:28:34 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 06:28:35 INFO - Downloading functools32-3.2.3-2.tar.gz 06:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:28:35 INFO - warning: no files found matching '*.txt' 06:28:35 INFO - no previously-included directories found matching 'build' 06:28:35 INFO - no previously-included directories found matching 'dist' 06:28:35 INFO - no previously-included directories found matching '.git*' 06:28:35 INFO - Installing collected packages: jsonschema, functools32 06:28:35 INFO - Running setup.py install for functools32 06:28:35 INFO - warning: no files found matching '*.txt' 06:28:35 INFO - no previously-included directories found matching 'build' 06:28:35 INFO - no previously-included directories found matching 'dist' 06:28:35 INFO - no previously-included directories found matching '.git*' 06:28:35 INFO - Successfully installed jsonschema functools32 06:28:35 INFO - Cleaning up... 06:28:35 INFO - Return code: 0 06:28:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:35 INFO - 'HOME': '/Users/cltbld', 06:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:35 INFO - 'LOGNAME': 'cltbld', 06:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:35 INFO - 'MOZ_NO_REMOTE': '1', 06:28:35 INFO - 'NO_EM_RESTART': '1', 06:28:35 INFO - 'PAGER': '/bin/cat', 06:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:35 INFO - 'PWD': '/builds/slave/test', 06:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:35 INFO - 'SHELL': '/bin/bash', 06:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:35 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:35 INFO - 'USER': 'cltbld', 06:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:35 INFO - 'XPC_FLAGS': '0x0', 06:28:35 INFO - 'XPC_SERVICE_NAME': '0', 06:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:28:36 INFO - Cleaning up... 06:28:36 INFO - Return code: 0 06:28:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:36 INFO - 'HOME': '/Users/cltbld', 06:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:36 INFO - 'LOGNAME': 'cltbld', 06:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:36 INFO - 'MOZ_NO_REMOTE': '1', 06:28:36 INFO - 'NO_EM_RESTART': '1', 06:28:36 INFO - 'PAGER': '/bin/cat', 06:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:36 INFO - 'PWD': '/builds/slave/test', 06:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:36 INFO - 'SHELL': '/bin/bash', 06:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:36 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:36 INFO - 'USER': 'cltbld', 06:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:36 INFO - 'XPC_FLAGS': '0x0', 06:28:36 INFO - 'XPC_SERVICE_NAME': '0', 06:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:36 INFO - Downloading/unpacking blobuploader==1.2.4 06:28:36 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 06:28:36 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 06:28:36 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 06:28:36 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 06:28:36 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 06:28:36 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 06:28:38 INFO - Downloading blobuploader-1.2.4.tar.gz 06:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:28:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:28:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:39 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 06:28:40 INFO - Downloading docopt-0.6.1.tar.gz 06:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:28:40 INFO - Installing collected packages: blobuploader, requests, docopt 06:28:40 INFO - Running setup.py install for blobuploader 06:28:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:28:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:28:40 INFO - Running setup.py install for requests 06:28:41 INFO - Running setup.py install for docopt 06:28:41 INFO - Successfully installed blobuploader requests docopt 06:28:41 INFO - Cleaning up... 06:28:41 INFO - Return code: 0 06:28:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:41 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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:41 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 06:28:41 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 06:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:41 INFO - 'HOME': '/Users/cltbld', 06:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:41 INFO - 'LOGNAME': 'cltbld', 06:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:41 INFO - 'MOZ_NO_REMOTE': '1', 06:28:41 INFO - 'NO_EM_RESTART': '1', 06:28:41 INFO - 'PAGER': '/bin/cat', 06:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:41 INFO - 'PWD': '/builds/slave/test', 06:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:41 INFO - 'SHELL': '/bin/bash', 06:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:41 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:41 INFO - 'USER': 'cltbld', 06:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:41 INFO - 'XPC_FLAGS': '0x0', 06:28:41 INFO - 'XPC_SERVICE_NAME': '0', 06:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:28:41 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mZ0bF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:28:41 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-dvHTko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:28:41 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_SGHeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:28:41 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-R9kmMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:28:41 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_WcGFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-xZvJH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-xXURNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8yTxmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-IL8GLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-hvjfkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-AoHyDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-jW4uLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-TE5fGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-XhR05O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-2DvjEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:28:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mBIamB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:28:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-2HSiKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:28:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:28:43 INFO - Running setup.py install for manifestparser 06:28:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:28:43 INFO - Running setup.py install for mozcrash 06:28:43 INFO - Running setup.py install for mozdebug 06:28:43 INFO - Running setup.py install for mozdevice 06:28:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:28:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:28:43 INFO - Running setup.py install for mozfile 06:28:44 INFO - Running setup.py install for mozhttpd 06:28:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for mozinfo 06:28:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for mozInstall 06:28:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for mozleak 06:28:44 INFO - Running setup.py install for mozlog 06:28:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for moznetwork 06:28:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for mozprocess 06:28:45 INFO - Running setup.py install for mozprofile 06:28:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozrunner 06:28:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozscreenshot 06:28:45 INFO - Running setup.py install for moztest 06:28:45 INFO - Running setup.py install for mozversion 06:28:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:28:45 INFO - Cleaning up... 06:28:45 INFO - Return code: 0 06:28:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:45 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')]} 06:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:45 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')]} 06:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:45 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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:45 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 06:28:45 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 06:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:45 INFO - 'HOME': '/Users/cltbld', 06:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:45 INFO - 'LOGNAME': 'cltbld', 06:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:45 INFO - 'MOZ_NO_REMOTE': '1', 06:28:45 INFO - 'NO_EM_RESTART': '1', 06:28:45 INFO - 'PAGER': '/bin/cat', 06:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:45 INFO - 'PWD': '/builds/slave/test', 06:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:45 INFO - 'SHELL': '/bin/bash', 06:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:45 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:45 INFO - 'USER': 'cltbld', 06:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:45 INFO - 'XPC_FLAGS': '0x0', 06:28:45 INFO - 'XPC_SERVICE_NAME': '0', 06:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-XfzZii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_4TnGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-J2Pmix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_5p51q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-JEmT2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-6VMeDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-oJWYtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:28:46 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)) 06:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:28:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-CEI69G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-pRdeVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-7TJmTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-GcWZqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-wvRkRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Yihlya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-oxH5RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-cPWbGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:47 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)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qiRHwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:28:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qyp4Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:28:47 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)) 06:28:47 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)) 06:28:47 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)) 06:28:47 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)) 06:28:47 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)) 06:28:47 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)) 06:28:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:47 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 06:28:47 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 06:28:47 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 06:28:47 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 06:28:47 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 06:28:47 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 06:28:50 INFO - Downloading blessings-1.6.tar.gz 06:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:28:50 INFO - Installing collected packages: blessings 06:28:50 INFO - Running setup.py install for blessings 06:28:50 INFO - Successfully installed blessings 06:28:50 INFO - Cleaning up... 06:28:50 INFO - Return code: 0 06:28:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:51 INFO - Reading from file tmpfile_stdout 06:28:51 INFO - Current package versions: 06:28:51 INFO - blessings == 1.6 06:28:51 INFO - blobuploader == 1.2.4 06:28:51 INFO - docopt == 0.6.1 06:28:51 INFO - functools32 == 3.2.3-2 06:28:51 INFO - jsonschema == 2.5.1 06:28:51 INFO - manifestparser == 1.1 06:28:51 INFO - mozInstall == 1.12 06:28:51 INFO - mozcrash == 1.0 06:28:51 INFO - mozdebug == 0.1 06:28:51 INFO - mozdevice == 0.48 06:28:51 INFO - mozfile == 1.2 06:28:51 INFO - mozhttpd == 0.7 06:28:51 INFO - mozinfo == 0.9 06:28:51 INFO - mozleak == 0.1 06:28:51 INFO - mozlog == 3.4 06:28:51 INFO - moznetwork == 0.27 06:28:51 INFO - mozprocess == 0.23 06:28:51 INFO - mozprofile == 0.28 06:28:51 INFO - mozrunner == 6.13 06:28:51 INFO - mozscreenshot == 0.1 06:28:51 INFO - mozsystemmonitor == 0.3 06:28:51 INFO - moztest == 0.7 06:28:51 INFO - mozversion == 1.4 06:28:51 INFO - psutil == 3.1.1 06:28:51 INFO - requests == 1.2.3 06:28:51 INFO - wsgiref == 0.1.2 06:28:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:51 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 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:51 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 06:28:51 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 06:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:51 INFO - 'HOME': '/Users/cltbld', 06:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:51 INFO - 'LOGNAME': 'cltbld', 06:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:51 INFO - 'MOZ_NO_REMOTE': '1', 06:28:51 INFO - 'NO_EM_RESTART': '1', 06:28:51 INFO - 'PAGER': '/bin/cat', 06:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:51 INFO - 'PWD': '/builds/slave/test', 06:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:51 INFO - 'SHELL': '/bin/bash', 06:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:51 INFO - 'USER': 'cltbld', 06:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:51 INFO - 'XPC_FLAGS': '0x0', 06:28:51 INFO - 'XPC_SERVICE_NAME': '0', 06:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 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)) 06:28:51 INFO - Cleaning up... 06:28:51 INFO - Return code: 0 06:28:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062d4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10624a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feddb5785c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, '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.CQDMFTshXi/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.83i5ymYflx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:51 INFO - 'HOME': '/Users/cltbld', 06:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:51 INFO - 'LOGNAME': 'cltbld', 06:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:51 INFO - 'MOZ_NO_REMOTE': '1', 06:28:51 INFO - 'NO_EM_RESTART': '1', 06:28:51 INFO - 'PAGER': '/bin/cat', 06:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:51 INFO - 'PWD': '/builds/slave/test', 06:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:51 INFO - 'SHELL': '/bin/bash', 06:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:28:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:51 INFO - 'USER': 'cltbld', 06:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:51 INFO - 'XPC_FLAGS': '0x0', 06:28:51 INFO - 'XPC_SERVICE_NAME': '0', 06:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:28:51 INFO - Cleaning up... 06:28:51 INFO - Return code: 0 06:28:51 INFO - Running post-action listener: _resource_record_post_action 06:28:51 INFO - Running post-action listener: _start_resource_monitoring 06:28:51 INFO - Starting resource monitoring. 06:28:51 INFO - [mozharness: 2016-12-29 14:28:51.825439Z] Finished create-virtualenv step (success) 06:28:51 INFO - [mozharness: 2016-12-29 14:28:51.826503Z] Running install step. 06:28:51 INFO - Running pre-action listener: _resource_record_pre_action 06:28:51 INFO - Running main action method: install 06:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:52 INFO - Reading from file tmpfile_stdout 06:28:52 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:28:52 INFO - Reading from file tmpfile_stdout 06:28:52 INFO - Output received: 06:28:52 INFO - Usage: mozinstall [options] installer 06:28:52 INFO - Options: 06:28:52 INFO - -h, --help show this help message and exit 06:28:52 INFO - -d DEST, --destination=DEST 06:28:52 INFO - Directory to install application into. [default: 06:28:52 INFO - "/builds/slave/test"] 06:28:52 INFO - --app=APP Application being installed. [default: firefox] 06:28:52 INFO - mkdir: /builds/slave/test/build/application 06:28:52 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'] 06:28:52 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 06:29:23 INFO - Reading from file tmpfile_stdout 06:29:23 INFO - Output received: 06:29:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:29:23 INFO - Running post-action listener: _resource_record_post_action 06:29:23 INFO - [mozharness: 2016-12-29 14:29:23.643594Z] Finished install step (success) 06:29:23 INFO - [mozharness: 2016-12-29 14:29:23.643726Z] Running run-tests step. 06:29:23 INFO - Running pre-action listener: _resource_record_pre_action 06:29:23 INFO - Running main action method: run_tests 06:29:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:29:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:29:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:29:23 INFO - 2016-12-29 06:29:23.658 screenresolution[2197:25490] starting screenresolution argv=screenresolution get 06:29:23 INFO - 2016-12-29 06:29:23.660 screenresolution[2197:25490] Display 0: 1600x1200x32@60 06:29:23 INFO - 2016-12-29 06:29:23.666 screenresolution[2198:25492] starting screenresolution argv=screenresolution list 06:29:23 INFO - Available Modes on Display 0 06:29:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:29:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:29:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:29:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:29:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:29:23 INFO - Intel Iris: 06:29:23 INFO - Chipset Model: Intel Iris 06:29:23 INFO - Type: GPU 06:29:23 INFO - Bus: Built-In 06:29:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:29:23 INFO - Vendor: Intel (0x8086) 06:29:23 INFO - Device ID: 0x0a2e 06:29:23 INFO - Revision ID: 0x0009 06:29:23 INFO - Displays: 06:29:23 INFO - Display: 06:29:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:29:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:29:23 INFO - Display Serial Number: 1600x1200 60 06:29:23 INFO - Main Display: Yes 06:29:23 INFO - Mirror: Off 06:29:23 INFO - Online: Yes 06:29:23 INFO - Rotation: Supported 06:29:23 INFO - Return code: 0 06:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:29:23 INFO - Python 2.7.3 06:29:23 INFO - Return code: 0 06:29:23 INFO - grabbing minidump binary from tooltool 06:29:23 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')]} 06:29:23 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 0x1060e8d30>, '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 0x105f4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060e0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:29:23 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 06:29:23 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 06:29:23 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 06:29:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:29:24 INFO - Return code: 0 06:29:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:29:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:29:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:29:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:29:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:29:24 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/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 06:29:24 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/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 06:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CQDMFTshXi/Render', 06:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:24 INFO - 'HOME': '/Users/cltbld', 06:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:24 INFO - 'LOGNAME': 'cltbld', 06:29:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:29:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:24 INFO - 'MOZ_NO_REMOTE': '1', 06:29:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:29:24 INFO - 'NO_EM_RESTART': '1', 06:29:24 INFO - 'PAGER': '/bin/cat', 06:29:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:24 INFO - 'PWD': '/builds/slave/test', 06:29:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:24 INFO - 'SHELL': '/bin/bash', 06:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83i5ymYflx/Listeners', 06:29:24 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:24 INFO - 'USER': 'cltbld', 06:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:24 INFO - 'XPC_FLAGS': '0x0', 06:29:24 INFO - 'XPC_SERVICE_NAME': '0', 06:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:24 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/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 06:29:24 INFO - mozversion application_buildid: 20161229041337 06:29:24 INFO - mozversion application_changeset: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 06:29:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:29:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:29:24 INFO - mozversion application_name: Firefox 06:29:24 INFO - mozversion application_remotingname: firefox-dev 06:29:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:29:24 INFO - mozversion application_vendor: Mozilla 06:29:24 INFO - mozversion application_version: 52.0a2 06:29:24 INFO - mozversion platform_buildid: 20161229041337 06:29:24 INFO - mozversion platform_changeset: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 06:29:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:29:24 INFO - mozversion platform_version: 52.0a2 06:29:24 INFO - using testdate: 1483021764 06:29:24 INFO - actual date: 1483021764 06:29:24 INFO - starting webserver on 'localhost:53529' 06:29:24 INFO - SUITE-START | Running 2 tests 06:29:24 INFO - TEST-START | dromaeo_css 06:29:24 INFO - operating with platform_type : mac_ 06:29:24 INFO - Initialising browser for dromaeo_css test... 06:29:24 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpP6QZyI/profile http://localhost:53529/getInfo.html) 06:29:27 INFO - PROCESS | 2208 | 2016-12-29 06:29:27.445 plugin-container[2210:25659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:29:27 INFO - PROCESS | 2208 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:27 INFO - PROCESS | 2208 | 2016-12-29 06:29:27.449 plugin-container[2210:25659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 06:29:27 INFO - PROCESS | 2208 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:27 INFO - PROCESS | 2208 | 2016-12-29 06:29:27.449 plugin-container[2210:25659] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:29:27 INFO - PROCESS | 2208 | __metrics Screen width/height:1600/1200 06:29:27 INFO - PROCESS | 2208 | colorDepth:24 06:29:27 INFO - PROCESS | 2208 | Browser inner width/height: 1024/689 06:29:27 INFO - PROCESS | 2208 | __metrics 06:29:27 ERROR - PROCESS | 2208 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 06:29:28 INFO - TEST-INFO | 2208: exit 0 06:29:28 INFO - Browser initialized. 06:29:28 INFO - Running cycle 1/1 for dromaeo_css test... 06:29:28 INFO - TEST-INFO | started process 2211 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpP6QZyI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:29:31 INFO - PROCESS | 2211 | 2016-12-29 06:29:31.438 plugin-container[2212:25786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 06:29:31 INFO - PROCESS | 2211 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:31 INFO - PROCESS | 2211 | 2016-12-29 06:29:31.439 plugin-container[2212:25786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8447, name = 'com.apple.CFPasteboardClient' 06:29:31 INFO - PROCESS | 2211 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:29:31 INFO - PROCESS | 2211 | 2016-12-29 06:29:31.439 plugin-container[2212:25786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:29:41 INFO - PROCESS | 2211 | RSS: Main: 229707776 06:29:41 INFO - PROCESS | 2211 | 06:30:30 INFO - PROCESS | 2211 | 1483021830685 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:32:44 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53529/tests/dromaeo/cssquery-ext.html) 06:32:46 INFO - PROCESS | 2211 | RSS: Main: 234020864 06:32:46 INFO - PROCESS | 2211 | 06:35:48 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-ext.html (next: http://localhost:53529/tests/dromaeo/cssquery-jquery.html) 06:35:49 INFO - PROCESS | 2211 | RSS: Main: 243593216 06:35:49 INFO - PROCESS | 2211 | 06:38:51 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53529/tests/dromaeo/cssquery-mootools.html) 06:38:51 INFO - PROCESS | 2211 | RSS: Main: 246214656 06:38:51 INFO - PROCESS | 2211 | 06:41:53 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53529/tests/dromaeo/cssquery-prototype.html) 06:41:54 INFO - PROCESS | 2211 | RSS: Main: 245567488 06:41:54 INFO - PROCESS | 2211 | 06:44:56 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53529/tests/dromaeo/cssquery-yui.html) 06:44:57 INFO - PROCESS | 2211 | RSS: Main: 252162048 06:44:57 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | Cycle 1(1): loaded http://localhost:53529/tests/dromaeo/cssquery-yui.html (next: http://localhost:53529/tests/dromaeo/cssquery-dojo.html) 06:48:00 INFO - PROCESS | 2211 | RSS: Main: 253595648 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | __start_tp_report 06:48:00 INFO - PROCESS | 2211 | _x_x_mozilla_page_load 06:48:00 INFO - PROCESS | 2211 | _x_x_mozilla_page_load_details 06:48:00 INFO - PROCESS | 2211 | |i|pagename|runs| 06:48:00 INFO - PROCESS | 2211 | |0;dojo.html;2834.333931526092;3003.4894068008434;3264.8572999450193;3315.403227419065;3351.480520519319;5563.527100196483;5594.524465420439;5610.747516361768;5646.237757902682;5668.178114173443;5503.724813759316;5579.46995035471;5580.525655319292;5588.022096133171;5621.634593751403;5081.060003899291;5115.846524604259;5129.102407078765;5155.7422128893595;5157.742112894359;5134.64057515974;5136.64043516954;5157.664751791141;5205.401378841425;5221.895562088756;5125.384953805548;5147.639665223436;5168.121419358718;5180.067587834188;5215.556677682402;5122.154844450662;5180.222966555009;5206.505381988705;5212.582993360522;5229.660072095302;5075.137226671474;5154.278401023859;5163.070647283487;5182.53357197854;5195.454477279869;5542.4457554244655;5580.804671836466;5596.300462442195;5607.859803504904;5653.717314134318;5883.61756485827;6020.96989515054;6070.8785824283705;6135.478484328837;6138.109974053782;6175;6189.164462797555;6213.906791398133;6215.2541694996435;6223.284322302948;5174.999999999963;5587.078132108198;5592.077307244259;5650.689212093374;5659.405762394889;5725.5133313667975;6210.658413787285;6225.595336303217;6232.781852635136;6242.812715618448;5466.207399926989;5475.890482190334;5967.910481342783;6082.452579267887;6112.327643959161;5212.582993360522;5316.946830531722;5948.226730525004;6114.755409783559;6144.324124346318;4599.908001840012;5989.610675306091;6039.943009973325;6078.331383547807;6388.4569811566535;5166.5866730661455;6155.784547540904;6179.938200618026;6190.845228869314;6191.628502289877;4798.904021919542;4829.73436460991;4834.927476087862;4842.346283251786;4886.169351210303;3868.2843673920415;4352.586504282088;4355.847545335898;4394.12117576486;4441.755703436277;4572.971081506701;5785.739641716133;5787.652740835563;5799.623024503394;5801.448862358069;5731.512821410227;5804.448577385142;5809.0705487122705;5855.853603659943;6166.445019948226;5455.09990851513;5456.454354564591;5492.615516913857;5697.515711164598;5749.281339832521;5392.892142157135;5400.513953744104;5409.810656627078;5416.268803711528;5430.755615997211;6106.39999602577;6167.889779839582;6188.876222475616;6200.689965501797;6201.1628430163155;6122.6938653068055;6139.102570481782;6142.560166306883;6182.505399568114;6193.071039344134;6115.301188035945;6149.569530132848;6177.505799536116;6262.780802671885;6302.338254483213;5898.852528686645;6071;6071.089336599545;6076.392360763888;6106.6946652668075;6135.263768347827;6142.9078563820685;6145.047517634595;6145.262568491809;6178.5366097543765;5810.215620891126;5898.528117750484;5954.612950158225;5972.940270597413;5983.37174596661;5805.448482394166;5907.172995780508;5915.024021036481;5927.14056461802;5930.199423077829;5746.569007324384;5792.565557583114;5919.792807251725;5938.703064846827;5956.761729530944;6003.939960600338;6016.097585362056;6052.061930400795;6063.393660633901;6073.423024812636;5749.310082790092;5750.6837123958585;5922.881542369215;5938.198343223609;5947.256592925884 06:48:00 INFO - PROCESS | 2211 | |1;ext.html;466.598725096417;484.86181438290095;510.9463506331833;527.4409126326094;532.712335338917;31630;38166.236675266475;39903.401448978286;39918.800405998;41883.58116418835;41158.38262426066;41478;41552;41563;41699.37450938238;9379.531023448835;9494.335396522247;9529.952350238274;9538.093881081286;9561.378510396802;9401.341906066578;9445.574949127305;9467.479288639122;9480.525973701322;9495.145436910676;9471.573779179953;9486.620535178585;9490.620375184984;9500.28747843911;9526.428414295164;9389.76525586859;9447.433154010712;9459.810803783921;9475.90524094764;9479.952600237024;8161.938947936789;8889.644414223423;8924.955375223148;9399.859002114974;9471.384360016576;39035.21929561421;39289.803550982346;39305.01737456558;39371.40942885859;39424.21151576981;3262.363839051386;3269.9182520436943;3340.5323254744353;3494.4234201356744;3746.4567637692653;28709.856450717612;29179.85410072936;29760.404791904257;29801.105966820815;30037.849810750806;3102.4880894652356;3152.9684703152902;3158.952615710766;3189.2983543620367;3274.6889045540634;509722.4513877444;512183;514294;514380;516304.4184779052;59665.40334596685;59853.40146598478;59856.40143598595;59944.10083848746;60060.79878402495;62588.74822503525;62971;63051.684741576915;63192.368076319566;63419;16054;16150.676986460205;16196.919015404848;16248.268827902773;16300.918495407685;12378.75242495145;12426.813597796041;12445.813312800316;12466.189697669623;12476.875231247754;16225.188740562924;17210.655786884192;17211.655766884593;17264.827351726573;17331.30674772995;6058.909116363258;6061.727222275008;6128.9693551531955;7138.892916606255;7185.748498802517;874.3573473496974;975.9024097590184;979.6669132494986;1003.5032658833924;1028.922830787694;28981.56527652087;29481.262968425963;29501.409971800444;30023.549646755317;30035.399292014037;972.1639390124487;1051.1800795379615;1073.838924161382;1076.784643071389;1093.4478088565224;14035.087719298417;14281;14693.853061469248;15252.38990440392;15527.301271442811;636828;641867;643786;645172;645267.773661138;44999;53488.46511534835;56058;56106;56216.718916405975;1242.8197911302811;1361.761691703948;1380.4892189889604;1388.3266615691382;1391.4016972701634;28356.865725370753;28693.85224590993;28789.42421151607;28826.711732882402;29675.109746707632;49960.500394995586;50294.748526257135;50419.747901260256;50428.49571504238;50476.99046019132;410732.8926710695;467076;467259;468326.658366706;468471;39857.80071099626;39976.20047598974;40026.1994760109;40276.1944761109;40394.59605404026;43991.27524799773;44517.33224001578;45366.77316613396;45471.31793023041;45572;37752.433713494866;38017.429738553394;38097.360471450724;38102.42846357251;39184.608153918096;36903;37476;37550;37843.81078094592;38337.42493862539;30115.849420752755;31795.36409271848;32150.839245804556;32392.352152956337;37938.43092353672;24206.636900446156;34079.82960085184;34313.828430857684;34427.31145377128;34540.30919381649;21484.027618181462;22178.44553886166;25216.243512694648;25514.617280741175;26085.347866303495 06:48:00 INFO - PROCESS | 2211 | |2;jquery.html;317.9793313434627;336.903982365026;370.98701545445914;375.0847931048243;400.9198160367927;18607.720884186747;18696.81303186968;19673.409797706056;19787.109580068896;20005.899970500126;17058.914705426454;19225.711614325795;19817.900910495464;19866.106025228863;20225.190992360287;5618.606697531174;5826.854328641775;5826.912596311059;5847.26909136358;5871.618344807595;4870.4885986971385;5454.127339625661;5701.6864072476;5772.0476121439915;5786.797462088828;5713.914291285613;5715.171300161474;5723.227364305828;5723.399043100477;5732.398098199692;6050.576459647827;6357.427831495164;6425.22897252328;6470.57941233823;6490.675466226669;5590.804321848756;5600.803971861006;5715.1713001614535;5732.971335143339;5781.479666830005;12965.805512917314;13021.86978130216;13156;13172.6706832329;13179.406926688227;2212.3805334506364;2241.9103235870707;2394.2817154853647;2472.9505409891703;2623.4884197581478;8668.60991255395;9772.169365603932;10345.482725863752;11292.435378231055;15638.687226255412;1859.7303391008363;1870.9719354209699;1892.7917929027797;1899.7435346228222;1910.4650697804636;112039;115499;120043.799562005;123471.14793278108;127906;11298.830517542243;11596.18826682141;11700.590479333183;11732.472038758277;11984.58053968107;14654.974151809483;14658.780118298235;14659.92670036643;14678.85321146775;14710.014148089871;8158.877616835753;8391.370647201484;8407.2433480987;8525.31797456202;8529.530875801891;8212.301954333829;8239.670413183405;8281.917180828115;8358.916410835935;8398.874016889751;4441.356003379492;4514.751688657089;4523;4549.931751023737;4603.079384123189;1892.8012558681417;1927.354336297356;1956.344624550763;1989.3136867780593;2003.278819624934;775.2518819339365;822.1819289806562;878.8198419324024;899.3839220134098;934.5047125023613;19519;20251.493712657008;20403.285884993955;20448.591028179355;20586.691199632016;889.208604342136;900.5902314447042;909.9681511147078;972.1687956796978;975.6926568131158;9363.62545498186;10633.574657013787;10763.46182690878;10770;10790.08284295828;555026;587657;588666;592803;602938;96873.51563242139;127086;127776;137042;140845.29577352048;868.2359523619301;946.7917058247175;1005.4620777883944;1005.5123265216364;1013.8884722680499;18900.905495472434;19212.71180932259;19369.51576210606;19399.709004365228;19554.120064597413;47839.04321913612;51849.48150518446;52859.735701321246;55800.441995579524;58007.419925800205;54469;55981;70293.94559081516;75701.10747231363;80115.39769204467;7130.643467826484;7858.253465920729;8000.159983201913;8150.429469937285;8328.208820162075;10234.374612803518;10979.341239525655;12994;13092.5417610387;13149.408276627384;9885.456299903575;9935.658686077577;10321.277510574488;10442.843357349799;10474.947625261824;11136.053435458076;11138.610148645083;11177.441127943734;11194.776104478027;11244.381559014333;10353.896461035294;11173.106151508022;11373.260738052331;11702.648920532398;11772.587959421722;7440.55356678601;7460.738874139379;7634.541927484369;7716.8456630868195;7727.729529466442;10317.587296508056;10480.266381353233;11130.053945414731;11443.370614616277;11554.306741595532 06:48:00 INFO - PROCESS | 2211 | |3;mootools.html;1445.86264304891;1479.408236705318;1642.2691902103568;1725.9654806903854;1775.7336399540059;6717.596944183347;7440.367568756648;7703.6918523259;8483.575821208939;8518.190771876669;7930.088039875416;8263.256306932375;8274;8357.707480238192;8391.412601117925;2313.3406979010983;2345.495718420539;2353.376355265856;2361.2325994051916;2362.2676970139228;1977.9604407911904;2334.614788559894;2371.0989823866994;2387.319613910036;2392.258399896029;2348.7064116985375;2351.7177938647387;2373.3473294843884;2375.9643605345846;2376.4771750214927;2316.5521549031855;2322.082777302956;2344.9296521104393;2350.612148995414;2358.8938497767554;1960.7549056367955;2354.0348457132663;2359.127122964497;2362.681038059857;2375.0499800079933;8394.748157555283;8463.449875758057;8482.957585212034;8487.915120848837;8502.362322825751;5090.33825602673;5249.1338929076655;5251.816186433494;5252.737363131828;5268.209768534713;3280.557124788147;3422.640622734627;3542.0967653248513;3585.784852908808;3594.02961200477;1031.9277650564463;1041.9147126716073;1076.6931424544046;1088.6951653537021;1099.1371773158112;11029.44852757359;11032.613858514913;11128.777424451546;11132.10943124548;11199.496022678999;4540.6821522493765;4635.1656701794;4737.407824021997;4750;4796.640251981115;7480.551166930001;9575.377600456088;9591.760205994906;9600.423974561549;9605.567749451291;5284.656497327662;5293.708846013506;5294.126469132627;5302.045631786315;5315.468453154731;4091.97954010234;4819.638527110481;4833.468318484964;4859.684120532154;4881.389826271716;3853.479780229633;3891.494105766289;3934.272159650474;3972.3644216925154;3978.6419222270133;1270.574357590199;1285.517930775959;1294.766941950458;1300.7138429545485;1304.4586496603993;773.3387953299848;878.885744853165;890.857462805948;909.4270609515962;918.6417297254076;5529.170624406372;5569.888602227933;5589.133684278943;5790.420957904176;5791.88416231673;977.413551868873;992.9453880036523;1004.633308842282;1026.2662196529368;1037.906588407032;3190.1865024418626;3704.481372607837;3781.4138808484727;3804;3807.181455986921;9801.166900813369;10108.191344692406;10233.43716095607;10257.435841028666;10262.589496420209;10184.388936663823;10186.898131018595;10206.591736330613;10207.693769186937;10225.795484090277;6762.120924279812;7810.6875724971505;7945.562994035384;7949.125596184415;7956.761297161094;5295.205719142159;5379;5443.346798384219;5534.948359811623;5606.355269144101;9279.536023198678;9357.53212339394;9400.388974716621;9482.715518534455;9535.61857525719;9516.666916657883;9587.424754514752;9589.712308630753;9590.952045239728;9754.609815607297;6234.251889773256;6311.368863113652;6717;6737.3599508046655;6865.485088618473;8008.6396112173925;8066.23370779775;8084.110747817617;8097.554634495159;8106.108328083788;7015.2283248843605;7126.109236345457;7175.31834475724;7213.891791623235;7240.384567311838;6704.363085506869;6765.1205343305055;6778.830529236809;6960.721571137098;7111.751088711871;6534.934650653433;6550.050242714875;6626.53614246998;6627.834304142435;6646.833829154117;4845.636577256791;4865.294532292868;4886.242632391985;4891.853244402673;4901.362822833009;4509.34614480905;4563.977180114078;4571.588557029883;4718.127146477913;4778.713277203379 06:48:00 INFO - PROCESS | 2211 | |4;prototype.html;279.2599611030768;296.2903845290529;301.61393416426955;303.59586910538764;310.1610144558968;4699.224627936395;4787.4255089389235;4860.604728751084;5127.052618962;5487.423820498845;4707.4586422561415;4775.116603428368;5149.789799397138;5193.610479214065;5308.893822123565;3362.6637336266363;4127.525334586531;4161.521425036114;4175.665946724254;4203.789810509477;3785.2807966486416;4049.493813273341;4204.411382406465;4205.810738516759;4209.789510524477;3619.384704600211;3752.418375151856;4095.399421367438;4101.712880098395;4126.298529250034;4100.261952848486;4112.609302116294;4120.445323899172;4123.525794533622;4123.5876412358975;3481.9825900870587;3625.29306785177;3630.7458477906557;3641.1625326175126;3651.3062518121737;4746.196445350744;4956.776945037482;5209.947900520984;5269.973650131725;5335.813246536352;4169.749815011109;4190.91618167638;4607.35497030416;4732.266498692708;4777.617790576745;4276.102018576103;5442;5496.273205043659;5567.025770490148;5623.662580245157;3791.9620803791886;3818.217265460574;3905.33609286422;3929.351656976595;3979.7214195006363;4817.879553011167;5945.4946329562035;5948.672822994734;5966.433188847052;5995.490383317379;3333.3499967506586;4004.7585248572454;4029.5768944261017;4033.616806403387;4037.6769858411258;3448.827558622109;3474.4961980512685;3886.6502014818802;4068.5321188063754;4088.5707000765087;4628.940121645903;4679.555442233051;4684.227102528044;4701.694389864716;4746.928796068062;5690.829275121753;5701.800436984686;5712.800051998243;5719.769289976913;5721.656700597978;1263.2357423758494;1272.4337669736879;1318.8944884409225;1322.2661422910185;1332.8337704508556;900.3292547052488;917.5183028909876;918.875951746519;920.60987908259;924.1728652855656;5041.697498150123;5044.571211446994;5048.3184770056305;5057.331080743345;5142.30578871855;4789.425268967677;4953.2570114483115;4996.125678006334;5235.712035837989;5546.861328466821;4339.5443478434945;4895.461499235082;5013.949860501421;5041.344625198774;5045.84862454127;2898.608667839449;2908.0661360195654;2953.3650265193087;2959.733623973853;2966.4363770883465;4534.229181039232;5147.536721694991;5417.404085550586;5908.645481271137;6187.721552530147;5578.721063946868;6125.018737820364;6137;6138.662373569497;6158.445739883432;4989.600831933437;5061.089873322854;5848.2689663792025;6212.9068063978175;6279.748810047547;5172.586193104467;5341.875003123217;5795.594828146272;5942.9690857662035;5952.73212705438;5332.200169974535;5379.0146468710245;5387.595930305321;5421.674699518042;5847.655039340891;5079.390473143247;5551.225945686626;6068.120122582289;6098.634081955097;6171.567990240819;4030.2544029354663;4076.3885417187657;4097.4263603095;4121.8969525762095;4142.937855932224;5898.351181370132;5944.494717949023;5944.910826337522;5951.940480595139;5987.34139244674;4430.692945581126;4865.489123642108;5650.378458369494;5723.170140329546;5802.738876750472;4636.814527419;5565.805196818092;5683.715814209356;5726.0838265877255;5753.453421924911;4277.850275240352;4668.766561671835;5153.45888681683;5417;5654.604177707521;5075.467075957119;5503.532199763065;5648.519875810603;5766;5789.2763404574425;4371;4826.565609095198;5396.271503346998;5400.8388196537935;5653.40639232891 06:48:00 INFO - PROCESS | 2211 | |5;yui.html;681.689831126837;685.4071228387446;687.9965600171998;742.543335848453;783.9608019599019;13800.309984500773;14315.284235788207;14847.183404912725;15006.324715387806;15051.548453546411;12060;13304.667383315422;13554.796678049837;13561.728765424685;13583.456661733544;797.3461761355676;804.0311424733212;817.9427440079197;818.7461886815106;819.3486178488116;803.5339503088225;807.8101646113158;808.4623725222749;808.9878651820227;809.1625167951142;821.2608652213027;838.3586556284447;845.1421806866036;851.3614788908319;852.4885068958637;814.5601375257354;824.0688022534557;824.5176571705559;837.8575642140851;840.474703310431;608.0210860514568;610.6763415389851;626.6115008694593;626.7493002798872;628.4595248086641;11995.64013079609;12080.697982550419;12377.62867113988;12401.441935112942;12426.5650702225;10995.780084398348;11033.724156896142;11336.489857956412;11428.085753139812;11752.118591105702;9803.765315270502;9917.553710083064;10135.54390052442;10349.948250258776;10353.689389318333;3485.3900567400606;3530.717542596586;3540.7167426606065;3546.2375589248436;3559.2703495783303;64997.67501162464;80517;81247.59376203081;82624;82927;8255.463394879314;8598.269147122439;8600;8608.956955215184;8722.563871806511;13328.400221990034;14371.425142994372;18072.548186295448;18122.184501697455;18123.275068997355;6341.048842673544;7337.633118344062;7431.888521672179;7495.287947644965;7768.961155194188;3775.962240377561;4130.690198235145;4157.272477316457;4261.593674087606;4290.184864875664;2660.7472290132405;3305.058058453329;3445.4831775233915;3446.1212390840174;3449.844756985942;560.8401605542401;607.183338409838;637.2544123375661;651.1762620285343;703.7572037647107;400.98997525061526;487.4832677362008;585.2070444547577;665.8801415745212;672.5493919074232;7473.887891681629;7796.337311328487;8488.405811593253;8823.779405514784;9352.812943741223;588.6438704583751;654.8592052708691;656.3698849104817;660.41883142834;678.8662932902065;3925.3130702127014;4411.779411029435;4515.2324104902245;4551.76048809353;4577.748223847654;103259.48370258251;103421.96578034274;104016;107271;110083;56469;72837;75764;80791.19208807837;84103;58468.70765646145;71036.93444598232;73437.26562734305;78186.6090669543;79895.60052199702;9355.953220233856;9686.64084755625;9699.903000970182;9826.606935722777;9837.655682051094;41120.79439602903;51370.743146285524;52056.438306850854;52936.73531632471;53591;44728.77635611801;75425;76113.47773044398;77989.2201077982;78188;11040.944795275973;11570.247933884271;11583.362915039754;11761.235519691192;11787.351695656822;13667.316634168053;13799.379027943565;13906.235157066054;14092.295385230902;14653.267336632913;12568.685782855136;12842;12940.352982350656;13029.869701303245;13096.148750331198;12327.50689972391;12792.74414511723;12859.228446293253;13445.991550633937;13463.932680336535;12637.368131593568;12815;12818.359082046047;13271.535496257584;13451.125676830974;10557.838637749996;11359.659210223706;11395.14536409756;11420.31478111316;11523.711907202387;11134.629977235538;11138.331700097697;11356.94321528387;11359.716007099889;11371.772564548497 06:48:00 INFO - PROCESS | 2211 | __end_tp_report 06:48:00 INFO - PROCESS | 2211 | __start_cc_report 06:48:00 INFO - PROCESS | 2211 | _x_x_mozilla_cycle_collect,378 06:48:00 INFO - PROCESS | 2211 | __end_cc_report 06:48:00 INFO - PROCESS | 2211 | __startTimestamp1483022880301__endTimestamp 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | ------- Summary: start ------- 06:48:00 INFO - PROCESS | 2211 | Number of tests: 6 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#0] dojo.html Cycles:165 Average:5602.25 Median:5785.74 stddev:648.75 (11.2%) stddev-sans-first:613.33 06:48:00 INFO - PROCESS | 2211 | Values: 2834.3 3003.5 3264.9 3315.4 3351.5 5563.5 5594.5 5610.7 5646.2 5668.2 5503.7 5579.5 5580.5 5588.0 5621.6 5081.1 5115.8 5129.1 5155.7 5157.7 5134.6 5136.6 5157.7 5205.4 5221.9 5125.4 5147.6 5168.1 5180.1 5215.6 5122.2 5180.2 5206.5 5212.6 5229.7 5075.1 5154.3 5163.1 5182.5 5195.5 5542.4 5580.8 5596.3 5607.9 5653.7 5883.6 6021.0 6070.9 6135.5 6138.1 6175.0 6189.2 6213.9 6215.3 6223.3 5175.0 5587.1 5592.1 5650.7 5659.4 5725.5 6210.7 6225.6 6232.8 6242.8 5466.2 5475.9 5967.9 6082.5 6112.3 5212.6 5316.9 5948.2 6114.8 6144.3 4599.9 5989.6 6039.9 6078.3 6388.5 5166.6 6155.8 6179.9 6190.8 6191.6 4798.9 4829.7 4834.9 4842.3 4886.2 3868.3 4352.6 4355.8 4394.1 4441.8 4573.0 5785.7 5787.7 5799.6 5801.4 5731.5 5804.4 5809.1 5855.9 6166.4 5455.1 5456.5 5492.6 5697.5 5749.3 5392.9 5400.5 5409.8 5416.3 5430.8 6106.4 6167.9 6188.9 6200.7 6201.2 6122.7 6139.1 6142.6 6182.5 6193.1 6115.3 6149.6 6177.5 6262.8 6302.3 5898.9 6071.0 6071.1 6076.4 6106.7 6135.3 6142.9 6145.0 6145.3 6178.5 5810.2 5898.5 5954.6 5972.9 5983.4 5805.4 5907.2 5915.0 5927.1 5930.2 5746.6 5792.6 5919.8 5938.7 5956.8 6003.9 6016.1 6052.1 6063.4 6073.4 5749.3 5750.7 5922.9 5938.2 5947.3 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#1] ext.html Cycles:180 Average:67236.89 Median:39947.50 stddev:145076.98 (363.2%) stddev-sans-first:145397.35 06:48:00 INFO - PROCESS | 2211 | Values: 466.6 484.9 510.9 527.4 532.7 31630.0 38166.2 39903.4 39918.8 41883.6 41158.4 41478.0 41552.0 41563.0 41699.4 9379.5 9494.3 9530.0 9538.1 9561.4 9401.3 9445.6 9467.5 9480.5 9495.1 9471.6 9486.6 9490.6 9500.3 9526.4 9389.8 9447.4 9459.8 9475.9 9480.0 8161.9 8889.6 8925.0 9399.9 9471.4 39035.2 39289.8 39305.0 39371.4 39424.2 3262.4 3269.9 3340.5 3494.4 3746.5 28709.9 29179.9 29760.4 29801.1 30037.8 3102.5 3153.0 3159.0 3189.3 3274.7 509722.5 512183.0 514294.0 514380.0 516304.4 59665.4 59853.4 59856.4 59944.1 60060.8 62588.7 62971.0 63051.7 63192.4 63419.0 16054.0 16150.7 16196.9 16248.3 16300.9 12378.8 12426.8 12445.8 12466.2 12476.9 16225.2 17210.7 17211.7 17264.8 17331.3 6058.9 6061.7 6129.0 7138.9 7185.7 874.4 975.9 979.7 1003.5 1028.9 28981.6 29481.3 29501.4 30023.5 30035.4 972.2 1051.2 1073.8 1076.8 1093.4 14035.1 14281.0 14693.9 15252.4 15527.3 636828.0 641867.0 643786.0 645172.0 645267.8 44999.0 53488.5 56058.0 56106.0 56216.7 1242.8 1361.8 1380.5 1388.3 1391.4 28356.9 28693.9 28789.4 28826.7 29675.1 49960.5 50294.7 50419.7 50428.5 50477.0 410732.9 467076.0 467259.0 468326.7 468471.0 39857.8 39976.2 40026.2 40276.2 40394.6 43991.3 44517.3 45366.8 45471.3 45572.0 37752.4 38017.4 38097.4 38102.4 39184.6 36903.0 37476.0 37550.0 37843.8 38337.4 30115.8 31795.4 32150.8 32392.4 37938.4 24206.6 34079.8 34313.8 34427.3 34540.3 21484.0 22178.4 25216.2 25514.6 26085.3 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#2] jquery.html Cycles:180 Average:33938.01 Median:2548.22 stddev:97862.31 (3840.4%) stddev-sans-first:98104.28 06:48:00 INFO - PROCESS | 2211 | Values: 318.0 336.9 371.0 375.1 400.9 18607.7 18696.8 19673.4 19787.1 20005.9 17058.9 19225.7 19817.9 19866.1 20225.2 5618.6 5826.9 5826.9 5847.3 5871.6 4870.5 5454.1 5701.7 5772.0 5786.8 5713.9 5715.2 5723.2 5723.4 5732.4 6050.6 6357.4 6425.2 6470.6 6490.7 5590.8 5600.8 5715.2 5733.0 5781.5 12965.8 13021.9 13156.0 13172.7 13179.4 2212.4 2241.9 2394.3 2473.0 2623.5 8668.6 9772.2 10345.5 11292.4 15638.7 1859.7 1871.0 1892.8 1899.7 1910.5 112039.0 115499.0 120043.8 123471.1 127906.0 11298.8 11596.2 11700.6 11732.5 11984.6 14655.0 14658.8 14659.9 14678.9 14710.0 8158.9 8391.4 8407.2 8525.3 8529.5 8212.3 8239.7 8281.9 8358.9 8398.9 4441.4 4514.8 4523.0 4549.9 4603.1 1892.8 1927.4 1956.3 1989.3 2003.3 775.3 822.2 878.8 899.4 934.5 19519.0 20251.5 20403.3 20448.6 20586.7 889.2 900.6 910.0 972.2 975.7 9363.6 10633.6 10763.5 10770.0 10790.1 555026.0 587657.0 588666.0 592803.0 602938.0 96873.5 127086.0 127776.0 137042.0 140845.3 868.2 946.8 1005.5 1005.5 1013.9 18900.9 19212.7 19369.5 19399.7 19554.1 47839.0 51849.5 52859.7 55800.4 58007.4 54469.0 55981.0 70293.9 75701.1 80115.4 7130.6 7858.3 8000.2 8150.4 8328.2 10234.4 10979.3 12994.0 13092.5 13149.4 9885.5 9935.7 10321.3 10442.8 10474.9 11136.1 11138.6 11177.4 11194.8 11244.4 10353.9 11173.1 11373.3 11702.6 11772.6 7440.6 7460.7 7634.5 7716.8 7727.7 10317.6 10480.3 11130.1 11443.4 11554.3 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#3] mootools.html Cycles:180 Average:5448.02 Median:4896.61 stddev:2985.88 (61.0%) stddev-sans-first:2979.11 06:48:00 INFO - PROCESS | 2211 | Values: 1445.9 1479.4 1642.3 1726.0 1775.7 6717.6 7440.4 7703.7 8483.6 8518.2 7930.1 8263.3 8274.0 8357.7 8391.4 2313.3 2345.5 2353.4 2361.2 2362.3 1978.0 2334.6 2371.1 2387.3 2392.3 2348.7 2351.7 2373.3 2376.0 2376.5 2316.6 2322.1 2344.9 2350.6 2358.9 1960.8 2354.0 2359.1 2362.7 2375.0 8394.7 8463.4 8483.0 8487.9 8502.4 5090.3 5249.1 5251.8 5252.7 5268.2 3280.6 3422.6 3542.1 3585.8 3594.0 1031.9 1041.9 1076.7 1088.7 1099.1 11029.4 11032.6 11128.8 11132.1 11199.5 4540.7 4635.2 4737.4 4750.0 4796.6 7480.6 9575.4 9591.8 9600.4 9605.6 5284.7 5293.7 5294.1 5302.0 5315.5 4092.0 4819.6 4833.5 4859.7 4881.4 3853.5 3891.5 3934.3 3972.4 3978.6 1270.6 1285.5 1294.8 1300.7 1304.5 773.3 878.9 890.9 909.4 918.6 5529.2 5569.9 5589.1 5790.4 5791.9 977.4 992.9 1004.6 1026.3 1037.9 3190.2 3704.5 3781.4 3804.0 3807.2 9801.2 10108.2 10233.4 10257.4 10262.6 10184.4 10186.9 10206.6 10207.7 10225.8 6762.1 7810.7 7945.6 7949.1 7956.8 5295.2 5379.0 5443.3 5534.9 5606.4 9279.5 9357.5 9400.4 9482.7 9535.6 9516.7 9587.4 9589.7 9591.0 9754.6 6234.3 6311.4 6717.0 6737.4 6865.5 8008.6 8066.2 8084.1 8097.6 8106.1 7015.2 7126.1 7175.3 7213.9 7240.4 6704.4 6765.1 6778.8 6960.7 7111.8 6534.9 6550.1 6626.5 6627.8 6646.8 4845.6 4865.3 4886.2 4891.9 4901.4 4509.3 4564.0 4571.6 4718.1 4778.7 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#4] prototype.html Cycles:180 Average:4539.71 Median:4973.19 stddev:1385.99 (27.9%) stddev-sans-first:1352.48 06:48:00 INFO - PROCESS | 2211 | Values: 279.3 296.3 301.6 303.6 310.2 4699.2 4787.4 4860.6 5127.1 5487.4 4707.5 4775.1 5149.8 5193.6 5308.9 3362.7 4127.5 4161.5 4175.7 4203.8 3785.3 4049.5 4204.4 4205.8 4209.8 3619.4 3752.4 4095.4 4101.7 4126.3 4100.3 4112.6 4120.4 4123.5 4123.6 3482.0 3625.3 3630.7 3641.2 3651.3 4746.2 4956.8 5209.9 5270.0 5335.8 4169.7 4190.9 4607.4 4732.3 4777.6 4276.1 5442.0 5496.3 5567.0 5623.7 3792.0 3818.2 3905.3 3929.4 3979.7 4817.9 5945.5 5948.7 5966.4 5995.5 3333.3 4004.8 4029.6 4033.6 4037.7 3448.8 3474.5 3886.7 4068.5 4088.6 4628.9 4679.6 4684.2 4701.7 4746.9 5690.8 5701.8 5712.8 5719.8 5721.7 1263.2 1272.4 1318.9 1322.3 1332.8 900.3 917.5 918.9 920.6 924.2 5041.7 5044.6 5048.3 5057.3 5142.3 4789.4 4953.3 4996.1 5235.7 5546.9 4339.5 4895.5 5013.9 5041.3 5045.8 2898.6 2908.1 2953.4 2959.7 2966.4 4534.2 5147.5 5417.4 5908.6 6187.7 5578.7 6125.0 6137.0 6138.7 6158.4 4989.6 5061.1 5848.3 6212.9 6279.7 5172.6 5341.9 5795.6 5943.0 5952.7 5332.2 5379.0 5387.6 5421.7 5847.7 5079.4 5551.2 6068.1 6098.6 6171.6 4030.3 4076.4 4097.4 4121.9 4142.9 5898.4 5944.5 5944.9 5951.9 5987.3 4430.7 4865.5 5650.4 5723.2 5802.7 4636.8 5565.8 5683.7 5726.1 5753.5 4277.9 4668.8 5153.5 5417.0 5654.6 5075.5 5503.5 5648.5 5766.0 5789.3 4371.0 4826.6 5396.3 5400.8 5653.4 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - PROCESS | 2211 | [#5] yui.html Cycles:180 Average:18653.41 Median:2532.62 stddev:26865.67 (1060.8%) stddev-sans-first:26907.14 06:48:00 INFO - PROCESS | 2211 | Values: 681.7 685.4 688.0 742.5 784.0 13800.3 14315.3 14847.2 15006.3 15051.5 12060.0 13304.7 13554.8 13561.7 13583.5 797.3 804.0 817.9 818.7 819.3 803.5 807.8 808.5 809.0 809.2 821.3 838.4 845.1 851.4 852.5 814.6 824.1 824.5 837.9 840.5 608.0 610.7 626.6 626.7 628.5 11995.6 12080.7 12377.6 12401.4 12426.6 10995.8 11033.7 11336.5 11428.1 11752.1 9803.8 9917.6 10135.5 10349.9 10353.7 3485.4 3530.7 3540.7 3546.2 3559.3 64997.7 80517.0 81247.6 82624.0 82927.0 8255.5 8598.3 8600.0 8609.0 8722.6 13328.4 14371.4 18072.5 18122.2 18123.3 6341.0 7337.6 7431.9 7495.3 7769.0 3776.0 4130.7 4157.3 4261.6 4290.2 2660.7 3305.1 3445.5 3446.1 3449.8 560.8 607.2 637.3 651.2 703.8 401.0 487.5 585.2 665.9 672.5 7473.9 7796.3 8488.4 8823.8 9352.8 588.6 654.9 656.4 660.4 678.9 3925.3 4411.8 4515.2 4551.8 4577.7 103259.5 103422.0 104016.0 107271.0 110083.0 56469.0 72837.0 75764.0 80791.2 84103.0 58468.7 71036.9 73437.3 78186.6 79895.6 9356.0 9686.6 9699.9 9826.6 9837.7 41120.8 51370.7 52056.4 52936.7 53591.0 44728.8 75425.0 76113.5 77989.2 78188.0 11040.9 11570.2 11583.4 11761.2 11787.4 13667.3 13799.4 13906.2 14092.3 14653.3 12568.7 12842.0 12940.4 13029.9 13096.1 12327.5 12792.7 12859.2 13446.0 13463.9 12637.4 12815.0 12818.4 13271.5 13451.1 10557.8 11359.7 11395.1 11420.3 11523.7 11134.6 11138.3 11356.9 11359.7 11371.8 06:48:00 INFO - PROCESS | 2211 | -------- Summary: end -------- 06:48:00 INFO - PROCESS | 2211 | 06:48:00 INFO - TEST-INFO | 2211: exit 0 06:48:00 INFO - TEST-OK | dromaeo_css | took 1116726ms 06:48:00 INFO - TEST-START | kraken 06:48:00 INFO - operating with platform_type : mac_ 06:48:00 INFO - Initialising browser for kraken test... 06:48:00 INFO - TEST-INFO | started process 2216 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpc2Pe1D/profile http://localhost:53529/getInfo.html) 06:48:03 INFO - PROCESS | 2216 | 2016-12-29 06:48:03.967 plugin-container[2217:27864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 06:48:03 INFO - PROCESS | 2216 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:03 INFO - PROCESS | 2216 | 2016-12-29 06:48:03.970 plugin-container[2217:27864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.CFPasteboardClient' 06:48:03 INFO - PROCESS | 2216 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:03 INFO - PROCESS | 2216 | 2016-12-29 06:48:03.970 plugin-container[2217:27864] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:48:04 INFO - PROCESS | 2216 | __metrics Screen width/height:1600/1200 06:48:04 INFO - PROCESS | 2216 | colorDepth:24 06:48:04 INFO - PROCESS | 2216 | Browser inner width/height: 1024/689 06:48:04 INFO - PROCESS | 2216 | __metrics 06:48:04 ERROR - PROCESS | 2216 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 06:48:04 INFO - TEST-INFO | 2216: exit 0 06:48:04 INFO - Browser initialized. 06:48:04 INFO - Running cycle 1/1 for kraken test... 06:48:04 INFO - TEST-INFO | started process 2218 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpc2Pe1D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:48:07 INFO - PROCESS | 2218 | 2016-12-29 06:48:07.602 plugin-container[2219:27986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 06:48:07 INFO - PROCESS | 2218 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:07 INFO - PROCESS | 2218 | 2016-12-29 06:48:07.603 plugin-container[2219:27986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 06:48:07 INFO - PROCESS | 2218 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:07 INFO - PROCESS | 2218 | 2016-12-29 06:48:07.603 plugin-container[2219:27986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:48:17 INFO - PROCESS | 2218 | RSS: Main: 229249024 06:48:17 INFO - PROCESS | 2218 | 06:48:19 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53529/tests/kraken/driver.html?testName=audio-beat-detection) 06:48:20 INFO - PROCESS | 2218 | RSS: Main: 241299456 06:48:20 INFO - PROCESS | 2218 | 06:48:23 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53529/tests/kraken/driver.html?testName=audio-dft) 06:48:24 INFO - PROCESS | 2218 | RSS: Main: 249118720 06:48:24 INFO - PROCESS | 2218 | 06:48:27 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53529/tests/kraken/driver.html?testName=audio-fft) 06:48:27 INFO - PROCESS | 2218 | RSS: Main: 252903424 06:48:27 INFO - PROCESS | 2218 | 06:48:29 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53529/tests/kraken/driver.html?testName=audio-oscillator) 06:48:30 INFO - PROCESS | 2218 | RSS: Main: 253165568 06:48:30 INFO - PROCESS | 2218 | 06:48:32 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53529/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:48:32 INFO - PROCESS | 2218 | RSS: Main: 253190144 06:48:32 INFO - PROCESS | 2218 | 06:48:35 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53529/tests/kraken/driver.html?testName=imaging-darkroom) 06:48:36 INFO - PROCESS | 2218 | RSS: Main: 258478080 06:48:36 INFO - PROCESS | 2218 | 06:48:39 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53529/tests/kraken/driver.html?testName=imaging-desaturate) 06:48:40 INFO - PROCESS | 2218 | RSS: Main: 258478080 06:48:40 INFO - PROCESS | 2218 | 06:48:42 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53529/tests/kraken/driver.html?testName=json-parse-financial) 06:48:43 INFO - PROCESS | 2218 | RSS: Main: 257970176 06:48:43 INFO - PROCESS | 2218 | 06:48:45 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53529/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:48:45 INFO - PROCESS | 2218 | RSS: Main: 257228800 06:48:45 INFO - PROCESS | 2218 | 06:48:47 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:48:48 INFO - PROCESS | 2218 | RSS: Main: 257171456 06:48:48 INFO - PROCESS | 2218 | 06:48:50 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:48:51 INFO - PROCESS | 2218 | RSS: Main: 257167360 06:48:51 INFO - PROCESS | 2218 | 06:48:54 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:48:55 INFO - PROCESS | 2218 | RSS: Main: 257359872 06:48:55 INFO - PROCESS | 2218 | 06:48:58 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:48:58 INFO - PROCESS | 2218 | RSS: Main: 257466368 06:48:58 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:53529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53529/tests/kraken/driver.html?testName=ai-astar) 06:49:00 INFO - PROCESS | 2218 | RSS: Main: 259006464 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | __start_tp_report 06:49:00 INFO - PROCESS | 2218 | _x_x_mozilla_page_load 06:49:00 INFO - PROCESS | 2218 | _x_x_mozilla_page_load_details 06:49:00 INFO - PROCESS | 2218 | |i|pagename|runs| 06:49:00 INFO - PROCESS | 2218 | |0;ai-astar;116;115;115;115;118;117;117;116;118;117 06:49:00 INFO - PROCESS | 2218 | |1;audio-beat-detection;141;115;116;152;116;131;114;102;166;138 06:49:00 INFO - PROCESS | 2218 | |2;audio-dft;197;200;200;196;194;193;198;195;199;199 06:49:00 INFO - PROCESS | 2218 | |3;audio-fft;82;59;61;86;62;59;60;60;78;59 06:49:00 INFO - PROCESS | 2218 | |4;audio-oscillator;85;70;89;70;70;73;114;84;58;72 06:49:00 INFO - PROCESS | 2218 | |5;imaging-gaussian-blur;90;88;105;87;92;86;89;87;96;86 06:49:00 INFO - PROCESS | 2218 | |6;imaging-darkroom;95;95;96;96;134;130;101;124;146;104 06:49:00 INFO - PROCESS | 2218 | |7;imaging-desaturate;88;72;132;137;90;70;144;99;90;76 06:49:00 INFO - PROCESS | 2218 | |8;json-parse-financial;58;58;56;62;60;62;61;60;61;61 06:49:00 INFO - PROCESS | 2218 | |9;json-stringify-tinderbox;75;63;73;84;78;76;70;71;71;86 06:49:00 INFO - PROCESS | 2218 | |10;stanford-crypto-aes;85;85;73;91;76;98;74;94;103;102 06:49:00 INFO - PROCESS | 2218 | |11;stanford-crypto-ccm;129;152;149;140;160;160;158;178;220;168 06:49:00 INFO - PROCESS | 2218 | |12;stanford-crypto-pbkdf2;170;171;170;164;171;166;206;224;165;224 06:49:00 INFO - PROCESS | 2218 | |13;stanford-crypto-sha256-iterative;71;67;67;69;68;69;69;66;68;66 06:49:00 INFO - PROCESS | 2218 | __end_tp_report 06:49:00 INFO - PROCESS | 2218 | __start_cc_report 06:49:00 INFO - PROCESS | 2218 | _x_x_mozilla_cycle_collect,992 06:49:00 INFO - PROCESS | 2218 | __end_cc_report 06:49:00 INFO - PROCESS | 2218 | __startTimestamp1483022940593__endTimestamp 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | ------- Summary: start ------- 06:49:00 INFO - PROCESS | 2218 | Number of tests: 14 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#0] ai-astar Cycles:10 Average:116.40 Median:117.00 stddev:1.17 (1.0%) stddev-sans-first:1.24 06:49:00 INFO - PROCESS | 2218 | Values: 116.0 115.0 115.0 115.0 118.0 117.0 117.0 116.0 118.0 117.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:134.50 stddev:20.04 (14.9%) stddev-sans-first:20.79 06:49:00 INFO - PROCESS | 2218 | Values: 141.0 115.0 116.0 152.0 116.0 131.0 114.0 102.0 166.0 138.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#2] audio-dft Cycles:10 Average:197.10 Median:198.50 stddev:2.51 (1.3%) stddev-sans-first:2.67 06:49:00 INFO - PROCESS | 2218 | Values: 197.0 200.0 200.0 196.0 194.0 193.0 198.0 195.0 199.0 199.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#3] audio-fft Cycles:10 Average:66.60 Median:61.50 stddev:10.83 (17.6%) stddev-sans-first:9.96 06:49:00 INFO - PROCESS | 2218 | Values: 82.0 59.0 61.0 86.0 62.0 59.0 60.0 60.0 78.0 59.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:72.50 stddev:15.47 (21.3%) stddev-sans-first:16.22 06:49:00 INFO - PROCESS | 2218 | Values: 85.0 70.0 89.0 70.0 70.0 73.0 114.0 84.0 58.0 72.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:88.50 stddev:5.93 (6.7%) stddev-sans-first:6.28 06:49:00 INFO - PROCESS | 2218 | Values: 90.0 88.0 105.0 87.0 92.0 86.0 89.0 87.0 96.0 86.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#6] imaging-darkroom Cycles:10 Average:112.10 Median:120.50 stddev:19.39 (16.1%) stddev-sans-first:19.55 06:49:00 INFO - PROCESS | 2218 | Values: 95.0 95.0 96.0 96.0 134.0 130.0 101.0 124.0 146.0 104.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:82.00 stddev:27.76 (33.8%) stddev-sans-first:29.11 06:49:00 INFO - PROCESS | 2218 | Values: 88.0 72.0 132.0 137.0 90.0 70.0 144.0 99.0 90.0 76.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:61.00 stddev:1.97 (3.2%) stddev-sans-first:1.96 06:49:00 INFO - PROCESS | 2218 | Values: 58.0 58.0 56.0 62.0 60.0 62.0 61.0 60.0 61.0 61.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:75.50 stddev:6.80 (9.0%) stddev-sans-first:7.21 06:49:00 INFO - PROCESS | 2218 | Values: 75.0 63.0 73.0 84.0 78.0 76.0 70.0 71.0 71.0 86.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.00 stddev:11.30 (13.3%) stddev-sans-first:11.93 06:49:00 INFO - PROCESS | 2218 | Values: 85.0 85.0 73.0 91.0 76.0 98.0 74.0 94.0 103.0 102.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#11] stanford-crypto-ccm Cycles:10 Average:161.40 Median:160.00 stddev:24.76 (15.5%) stddev-sans-first:23.32 06:49:00 INFO - PROCESS | 2218 | Values: 129.0 152.0 149.0 140.0 160.0 160.0 158.0 178.0 220.0 168.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:171.00 stddev:24.70 (14.4%) stddev-sans-first:25.74 06:49:00 INFO - PROCESS | 2218 | Values: 170.0 171.0 170.0 164.0 171.0 166.0 206.0 224.0 165.0 224.0 06:49:00 INFO - PROCESS | 2218 | 06:49:00 INFO - PROCESS | 2218 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:1.56 (2.3%) stddev-sans-first:1.22 06:49:00 INFO - PROCESS | 2218 | Values: 71.0 67.0 67.0 69.0 68.0 69.0 69.0 66.0 68.0 66.0 06:49:00 INFO - PROCESS | 2218 | -------- Summary: end -------- 06:49:00 INFO - PROCESS | 2218 | 06:49:01 INFO - TEST-INFO | 2218: exit 0 06:49:01 INFO - TEST-OK | kraken | took 60627ms 06:49:01 INFO - SUITE-END | took 1177s 06:49:01 INFO - Completed test suite (00:19:37) 06:49:01 INFO - javascript benchmark 06:49:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7081.588641483137, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2834.333931526092, 3003.4894068008434, 3264.8572999450193, 3315.403227419065, 3351.480520519319, 5563.527100196483, 5594.524465420439, 5610.747516361768, 5646.237757902682, 5668.178114173443, 5503.724813759316, 5579.46995035471, 5580.525655319292, 5588.022096133171, 5621.634593751403, 5081.060003899291, 5115.846524604259, 5129.102407078765, 5155.7422128893595, 5157.742112894359, 5134.64057515974, 5136.64043516954, 5157.664751791141, 5205.401378841425, 5221.895562088756, 5125.384953805548, 5147.639665223436, 5168.121419358718, 5180.067587834188, 5215.556677682402, 5122.154844450662, 5180.222966555009, 5206.505381988705, 5212.582993360522, 5229.660072095302, 5075.137226671474, 5154.278401023859, 5163.070647283487, 5182.53357197854, 5195.454477279869, 5542.4457554244655, 5580.804671836466, 5596.300462442195, 5607.859803504904, 5653.717314134318, 5883.61756485827, 6020.96989515054, 6070.8785824283705, 6135.478484328837, 6138.109974053782, 6175.0, 6189.164462797555, 6213.906791398133, 6215.2541694996435, 6223.284322302948, 5174.999999999963, 5587.078132108198, 5592.077307244259, 5650.689212093374, 5659.405762394889, 5725.5133313667975, 6210.658413787285, 6225.595336303217, 6232.781852635136, 6242.812715618448, 5466.207399926989, 5475.890482190334, 5967.910481342783, 6082.452579267887, 6112.327643959161, 5212.582993360522, 5316.946830531722, 5948.226730525004, 6114.755409783559, 6144.324124346318, 4599.908001840012, 5989.610675306091, 6039.943009973325, 6078.331383547807, 6388.4569811566535, 5166.5866730661455, 6155.784547540904, 6179.938200618026, 6190.845228869314, 6191.628502289877, 4798.904021919542, 4829.73436460991, 4834.927476087862, 4842.346283251786, 4886.169351210303, 3868.2843673920415, 4352.586504282088, 4355.847545335898, 4394.12117576486, 4441.755703436277, 4572.971081506701, 5785.739641716133, 5787.652740835563, 5799.623024503394, 5801.448862358069, 5731.512821410227, 5804.448577385142, 5809.0705487122705, 5855.853603659943, 6166.445019948226, 5455.09990851513, 5456.454354564591, 5492.615516913857, 5697.515711164598, 5749.281339832521, 5392.892142157135, 5400.513953744104, 5409.810656627078, 5416.268803711528, 5430.755615997211, 6106.39999602577, 6167.889779839582, 6188.876222475616, 6200.689965501797, 6201.1628430163155, 6122.6938653068055, 6139.102570481782, 6142.560166306883, 6182.505399568114, 6193.071039344134, 6115.301188035945, 6149.569530132848, 6177.505799536116, 6262.780802671885, 6302.338254483213, 5898.852528686645, 6071.0, 6071.089336599545, 6076.392360763888, 6106.6946652668075, 6135.263768347827, 6142.9078563820685, 6145.047517634595, 6145.262568491809, 6178.5366097543765, 5810.215620891126, 5898.528117750484, 5954.612950158225, 5972.940270597413, 5983.37174596661, 5805.448482394166, 5907.172995780508, 5915.024021036481, 5927.14056461802, 5930.199423077829, 5746.569007324384, 5792.565557583114, 5919.792807251725, 5938.703064846827, 5956.761729530944, 6003.939960600338, 6016.097585362056, 6052.061930400795, 6063.393660633901, 6073.423024812636, 5749.310082790092, 5750.6837123958585, 5922.881542369215, 5938.198343223609, 5947.256592925884], "value": 5560.69994063404, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.598725096417, 484.86181438290095, 510.9463506331833, 527.4409126326094, 532.712335338917, 31630.0, 38166.236675266475, 39903.401448978286, 39918.800405998, 41883.58116418835, 41158.38262426066, 41478.0, 41552.0, 41563.0, 41699.37450938238, 9379.531023448835, 9494.335396522247, 9529.952350238274, 9538.093881081286, 9561.378510396802, 9401.341906066578, 9445.574949127305, 9467.479288639122, 9480.525973701322, 9495.145436910676, 9471.573779179953, 9486.620535178585, 9490.620375184984, 9500.28747843911, 9526.428414295164, 9389.76525586859, 9447.433154010712, 9459.810803783921, 9475.90524094764, 9479.952600237024, 8161.938947936789, 8889.644414223423, 8924.955375223148, 9399.859002114974, 9471.384360016576, 39035.21929561421, 39289.803550982346, 39305.01737456558, 39371.40942885859, 39424.21151576981, 3262.363839051386, 3269.9182520436943, 3340.5323254744353, 3494.4234201356744, 3746.4567637692653, 28709.856450717612, 29179.85410072936, 29760.404791904257, 29801.105966820815, 30037.849810750806, 3102.4880894652356, 3152.9684703152902, 3158.952615710766, 3189.2983543620367, 3274.6889045540634, 509722.4513877444, 512183.0, 514294.0, 514380.0, 516304.4184779052, 59665.40334596685, 59853.40146598478, 59856.40143598595, 59944.10083848746, 60060.79878402495, 62588.74822503525, 62971.0, 63051.684741576915, 63192.368076319566, 63419.0, 16054.0, 16150.676986460205, 16196.919015404848, 16248.268827902773, 16300.918495407685, 12378.75242495145, 12426.813597796041, 12445.813312800316, 12466.189697669623, 12476.875231247754, 16225.188740562924, 17210.655786884192, 17211.655766884593, 17264.827351726573, 17331.30674772995, 6058.909116363258, 6061.727222275008, 6128.9693551531955, 7138.892916606255, 7185.748498802517, 874.3573473496974, 975.9024097590184, 979.6669132494986, 1003.5032658833924, 1028.922830787694, 28981.56527652087, 29481.262968425963, 29501.409971800444, 30023.549646755317, 30035.399292014037, 972.1639390124487, 1051.1800795379615, 1073.838924161382, 1076.784643071389, 1093.4478088565224, 14035.087719298417, 14281.0, 14693.853061469248, 15252.38990440392, 15527.301271442811, 636828.0, 641867.0, 643786.0, 645172.0, 645267.773661138, 44999.0, 53488.46511534835, 56058.0, 56106.0, 56216.718916405975, 1242.8197911302811, 1361.761691703948, 1380.4892189889604, 1388.3266615691382, 1391.4016972701634, 28356.865725370753, 28693.85224590993, 28789.42421151607, 28826.711732882402, 29675.109746707632, 49960.500394995586, 50294.748526257135, 50419.747901260256, 50428.49571504238, 50476.99046019132, 410732.8926710695, 467076.0, 467259.0, 468326.658366706, 468471.0, 39857.80071099626, 39976.20047598974, 40026.1994760109, 40276.1944761109, 40394.59605404026, 43991.27524799773, 44517.33224001578, 45366.77316613396, 45471.31793023041, 45572.0, 37752.433713494866, 38017.429738553394, 38097.360471450724, 38102.42846357251, 39184.608153918096, 36903.0, 37476.0, 37550.0, 37843.81078094592, 38337.42493862539, 30115.849420752755, 31795.36409271848, 32150.839245804556, 32392.352152956337, 37938.43092353672, 24206.636900446156, 34079.82960085184, 34313.828430857684, 34427.31145377128, 34540.30919381649, 21484.027618181462, 22178.44553886166, 25216.243512694648, 25514.617280741175, 26085.347866303495], "value": 19528.218809215952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.9793313434627, 336.903982365026, 370.98701545445914, 375.0847931048243, 400.9198160367927, 18607.720884186747, 18696.81303186968, 19673.409797706056, 19787.109580068896, 20005.899970500126, 17058.914705426454, 19225.711614325795, 19817.900910495464, 19866.106025228863, 20225.190992360287, 5618.606697531174, 5826.854328641775, 5826.912596311059, 5847.26909136358, 5871.618344807595, 4870.4885986971385, 5454.127339625661, 5701.6864072476, 5772.0476121439915, 5786.797462088828, 5713.914291285613, 5715.171300161474, 5723.227364305828, 5723.399043100477, 5732.398098199692, 6050.576459647827, 6357.427831495164, 6425.22897252328, 6470.57941233823, 6490.675466226669, 5590.804321848756, 5600.803971861006, 5715.1713001614535, 5732.971335143339, 5781.479666830005, 12965.805512917314, 13021.86978130216, 13156.0, 13172.6706832329, 13179.406926688227, 2212.3805334506364, 2241.9103235870707, 2394.2817154853647, 2472.9505409891703, 2623.4884197581478, 8668.60991255395, 9772.169365603932, 10345.482725863752, 11292.435378231055, 15638.687226255412, 1859.7303391008363, 1870.9719354209699, 1892.7917929027797, 1899.7435346228222, 1910.4650697804636, 112039.0, 115499.0, 120043.799562005, 123471.14793278108, 127906.0, 11298.830517542243, 11596.18826682141, 11700.590479333183, 11732.472038758277, 11984.58053968107, 14654.974151809483, 14658.780118298235, 14659.92670036643, 14678.85321146775, 14710.014148089871, 8158.877616835753, 8391.370647201484, 8407.2433480987, 8525.31797456202, 8529.530875801891, 8212.301954333829, 8239.670413183405, 8281.917180828115, 8358.916410835935, 8398.874016889751, 4441.356003379492, 4514.751688657089, 4523.0, 4549.931751023737, 4603.079384123189, 1892.8012558681417, 1927.354336297356, 1956.344624550763, 1989.3136867780593, 2003.278819624934, 775.2518819339365, 822.1819289806562, 878.8198419324024, 899.3839220134098, 934.5047125023613, 19519.0, 20251.493712657008, 20403.285884993955, 20448.591028179355, 20586.691199632016, 889.208604342136, 900.5902314447042, 909.9681511147078, 972.1687956796978, 975.6926568131158, 9363.62545498186, 10633.574657013787, 10763.46182690878, 10770.0, 10790.08284295828, 555026.0, 587657.0, 588666.0, 592803.0, 602938.0, 96873.51563242139, 127086.0, 127776.0, 137042.0, 140845.29577352048, 868.2359523619301, 946.7917058247175, 1005.4620777883944, 1005.5123265216364, 1013.8884722680499, 18900.905495472434, 19212.71180932259, 19369.51576210606, 19399.709004365228, 19554.120064597413, 47839.04321913612, 51849.48150518446, 52859.735701321246, 55800.441995579524, 58007.419925800205, 54469.0, 55981.0, 70293.94559081516, 75701.10747231363, 80115.39769204467, 7130.643467826484, 7858.253465920729, 8000.159983201913, 8150.429469937285, 8328.208820162075, 10234.374612803518, 10979.341239525655, 12994.0, 13092.5417610387, 13149.408276627384, 9885.456299903575, 9935.658686077577, 10321.277510574488, 10442.843357349799, 10474.947625261824, 11136.053435458076, 11138.610148645083, 11177.441127943734, 11194.776104478027, 11244.381559014333, 10353.896461035294, 11173.106151508022, 11373.260738052331, 11702.648920532398, 11772.587959421722, 7440.55356678601, 7460.738874139379, 7634.541927484369, 7716.8456630868195, 7727.729529466442, 10317.587296508056, 10480.266381353233, 11130.053945414731, 11443.370614616277, 11554.306741595532], "value": 9263.980100748804, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.86264304891, 1479.408236705318, 1642.2691902103568, 1725.9654806903854, 1775.7336399540059, 6717.596944183347, 7440.367568756648, 7703.6918523259, 8483.575821208939, 8518.190771876669, 7930.088039875416, 8263.256306932375, 8274.0, 8357.707480238192, 8391.412601117925, 2313.3406979010983, 2345.495718420539, 2353.376355265856, 2361.2325994051916, 2362.2676970139228, 1977.9604407911904, 2334.614788559894, 2371.0989823866994, 2387.319613910036, 2392.258399896029, 2348.7064116985375, 2351.7177938647387, 2373.3473294843884, 2375.9643605345846, 2376.4771750214927, 2316.5521549031855, 2322.082777302956, 2344.9296521104393, 2350.612148995414, 2358.8938497767554, 1960.7549056367955, 2354.0348457132663, 2359.127122964497, 2362.681038059857, 2375.0499800079933, 8394.748157555283, 8463.449875758057, 8482.957585212034, 8487.915120848837, 8502.362322825751, 5090.33825602673, 5249.1338929076655, 5251.816186433494, 5252.737363131828, 5268.209768534713, 3280.557124788147, 3422.640622734627, 3542.0967653248513, 3585.784852908808, 3594.02961200477, 1031.9277650564463, 1041.9147126716073, 1076.6931424544046, 1088.6951653537021, 1099.1371773158112, 11029.44852757359, 11032.613858514913, 11128.777424451546, 11132.10943124548, 11199.496022678999, 4540.6821522493765, 4635.1656701794, 4737.407824021997, 4750.0, 4796.640251981115, 7480.551166930001, 9575.377600456088, 9591.760205994906, 9600.423974561549, 9605.567749451291, 5284.656497327662, 5293.708846013506, 5294.126469132627, 5302.045631786315, 5315.468453154731, 4091.97954010234, 4819.638527110481, 4833.468318484964, 4859.684120532154, 4881.389826271716, 3853.479780229633, 3891.494105766289, 3934.272159650474, 3972.3644216925154, 3978.6419222270133, 1270.574357590199, 1285.517930775959, 1294.766941950458, 1300.7138429545485, 1304.4586496603993, 773.3387953299848, 878.885744853165, 890.857462805948, 909.4270609515962, 918.6417297254076, 5529.170624406372, 5569.888602227933, 5589.133684278943, 5790.420957904176, 5791.88416231673, 977.413551868873, 992.9453880036523, 1004.633308842282, 1026.2662196529368, 1037.906588407032, 3190.1865024418626, 3704.481372607837, 3781.4138808484727, 3804.0, 3807.181455986921, 9801.166900813369, 10108.191344692406, 10233.43716095607, 10257.435841028666, 10262.589496420209, 10184.388936663823, 10186.898131018595, 10206.591736330613, 10207.693769186937, 10225.795484090277, 6762.120924279812, 7810.6875724971505, 7945.562994035384, 7949.125596184415, 7956.761297161094, 5295.205719142159, 5379.0, 5443.346798384219, 5534.948359811623, 5606.355269144101, 9279.536023198678, 9357.53212339394, 9400.388974716621, 9482.715518534455, 9535.61857525719, 9516.666916657883, 9587.424754514752, 9589.712308630753, 9590.952045239728, 9754.609815607297, 6234.251889773256, 6311.368863113652, 6717.0, 6737.3599508046655, 6865.485088618473, 8008.6396112173925, 8066.23370779775, 8084.110747817617, 8097.554634495159, 8106.108328083788, 7015.2283248843605, 7126.109236345457, 7175.31834475724, 7213.891791623235, 7240.384567311838, 6704.363085506869, 6765.1205343305055, 6778.830529236809, 6960.721571137098, 7111.751088711871, 6534.934650653433, 6550.050242714875, 6626.53614246998, 6627.834304142435, 6646.833829154117, 4845.636577256791, 4865.294532292868, 4886.242632391985, 4891.853244402673, 4901.362822833009, 4509.34614480905, 4563.977180114078, 4571.588557029883, 4718.127146477913, 4778.713277203379], "value": 4434.54328108087, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.2599611030768, 296.2903845290529, 301.61393416426955, 303.59586910538764, 310.1610144558968, 4699.224627936395, 4787.4255089389235, 4860.604728751084, 5127.052618962, 5487.423820498845, 4707.4586422561415, 4775.116603428368, 5149.789799397138, 5193.610479214065, 5308.893822123565, 3362.6637336266363, 4127.525334586531, 4161.521425036114, 4175.665946724254, 4203.789810509477, 3785.2807966486416, 4049.493813273341, 4204.411382406465, 4205.810738516759, 4209.789510524477, 3619.384704600211, 3752.418375151856, 4095.399421367438, 4101.712880098395, 4126.298529250034, 4100.261952848486, 4112.609302116294, 4120.445323899172, 4123.525794533622, 4123.5876412358975, 3481.9825900870587, 3625.29306785177, 3630.7458477906557, 3641.1625326175126, 3651.3062518121737, 4746.196445350744, 4956.776945037482, 5209.947900520984, 5269.973650131725, 5335.813246536352, 4169.749815011109, 4190.91618167638, 4607.35497030416, 4732.266498692708, 4777.617790576745, 4276.102018576103, 5442.0, 5496.273205043659, 5567.025770490148, 5623.662580245157, 3791.9620803791886, 3818.217265460574, 3905.33609286422, 3929.351656976595, 3979.7214195006363, 4817.879553011167, 5945.4946329562035, 5948.672822994734, 5966.433188847052, 5995.490383317379, 3333.3499967506586, 4004.7585248572454, 4029.5768944261017, 4033.616806403387, 4037.6769858411258, 3448.827558622109, 3474.4961980512685, 3886.6502014818802, 4068.5321188063754, 4088.5707000765087, 4628.940121645903, 4679.555442233051, 4684.227102528044, 4701.694389864716, 4746.928796068062, 5690.829275121753, 5701.800436984686, 5712.800051998243, 5719.769289976913, 5721.656700597978, 1263.2357423758494, 1272.4337669736879, 1318.8944884409225, 1322.2661422910185, 1332.8337704508556, 900.3292547052488, 917.5183028909876, 918.875951746519, 920.60987908259, 924.1728652855656, 5041.697498150123, 5044.571211446994, 5048.3184770056305, 5057.331080743345, 5142.30578871855, 4789.425268967677, 4953.2570114483115, 4996.125678006334, 5235.712035837989, 5546.861328466821, 4339.5443478434945, 4895.461499235082, 5013.949860501421, 5041.344625198774, 5045.84862454127, 2898.608667839449, 2908.0661360195654, 2953.3650265193087, 2959.733623973853, 2966.4363770883465, 4534.229181039232, 5147.536721694991, 5417.404085550586, 5908.645481271137, 6187.721552530147, 5578.721063946868, 6125.018737820364, 6137.0, 6138.662373569497, 6158.445739883432, 4989.600831933437, 5061.089873322854, 5848.2689663792025, 6212.9068063978175, 6279.748810047547, 5172.586193104467, 5341.875003123217, 5795.594828146272, 5942.9690857662035, 5952.73212705438, 5332.200169974535, 5379.0146468710245, 5387.595930305321, 5421.674699518042, 5847.655039340891, 5079.390473143247, 5551.225945686626, 6068.120122582289, 6098.634081955097, 6171.567990240819, 4030.2544029354663, 4076.3885417187657, 4097.4263603095, 4121.8969525762095, 4142.937855932224, 5898.351181370132, 5944.494717949023, 5944.910826337522, 5951.940480595139, 5987.34139244674, 4430.692945581126, 4865.489123642108, 5650.378458369494, 5723.170140329546, 5802.738876750472, 4636.814527419, 5565.805196818092, 5683.715814209356, 5726.0838265877255, 5753.453421924911, 4277.850275240352, 4668.766561671835, 5153.45888681683, 5417.0, 5654.604177707521, 5075.467075957119, 5503.532199763065, 5648.519875810603, 5766.0, 5789.2763404574425, 4371.0, 4826.565609095198, 5396.271503346998, 5400.8388196537935, 5653.40639232891], "value": 4099.1270149179145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.689831126837, 685.4071228387446, 687.9965600171998, 742.543335848453, 783.9608019599019, 13800.309984500773, 14315.284235788207, 14847.183404912725, 15006.324715387806, 15051.548453546411, 12060.0, 13304.667383315422, 13554.796678049837, 13561.728765424685, 13583.456661733544, 797.3461761355676, 804.0311424733212, 817.9427440079197, 818.7461886815106, 819.3486178488116, 803.5339503088225, 807.8101646113158, 808.4623725222749, 808.9878651820227, 809.1625167951142, 821.2608652213027, 838.3586556284447, 845.1421806866036, 851.3614788908319, 852.4885068958637, 814.5601375257354, 824.0688022534557, 824.5176571705559, 837.8575642140851, 840.474703310431, 608.0210860514568, 610.6763415389851, 626.6115008694593, 626.7493002798872, 628.4595248086641, 11995.64013079609, 12080.697982550419, 12377.62867113988, 12401.441935112942, 12426.5650702225, 10995.780084398348, 11033.724156896142, 11336.489857956412, 11428.085753139812, 11752.118591105702, 9803.765315270502, 9917.553710083064, 10135.54390052442, 10349.948250258776, 10353.689389318333, 3485.3900567400606, 3530.717542596586, 3540.7167426606065, 3546.2375589248436, 3559.2703495783303, 64997.67501162464, 80517.0, 81247.59376203081, 82624.0, 82927.0, 8255.463394879314, 8598.269147122439, 8600.0, 8608.956955215184, 8722.563871806511, 13328.400221990034, 14371.425142994372, 18072.548186295448, 18122.184501697455, 18123.275068997355, 6341.048842673544, 7337.633118344062, 7431.888521672179, 7495.287947644965, 7768.961155194188, 3775.962240377561, 4130.690198235145, 4157.272477316457, 4261.593674087606, 4290.184864875664, 2660.7472290132405, 3305.058058453329, 3445.4831775233915, 3446.1212390840174, 3449.844756985942, 560.8401605542401, 607.183338409838, 637.2544123375661, 651.1762620285343, 703.7572037647107, 400.98997525061526, 487.4832677362008, 585.2070444547577, 665.8801415745212, 672.5493919074232, 7473.887891681629, 7796.337311328487, 8488.405811593253, 8823.779405514784, 9352.812943741223, 588.6438704583751, 654.8592052708691, 656.3698849104817, 660.41883142834, 678.8662932902065, 3925.3130702127014, 4411.779411029435, 4515.2324104902245, 4551.76048809353, 4577.748223847654, 103259.48370258251, 103421.96578034274, 104016.0, 107271.0, 110083.0, 56469.0, 72837.0, 75764.0, 80791.19208807837, 84103.0, 58468.70765646145, 71036.93444598232, 73437.26562734305, 78186.6090669543, 79895.60052199702, 9355.953220233856, 9686.64084755625, 9699.903000970182, 9826.606935722777, 9837.655682051094, 41120.79439602903, 51370.743146285524, 52056.438306850854, 52936.73531632471, 53591.0, 44728.77635611801, 75425.0, 76113.47773044398, 77989.2201077982, 78188.0, 11040.944795275973, 11570.247933884271, 11583.362915039754, 11761.235519691192, 11787.351695656822, 13667.316634168053, 13799.379027943565, 13906.235157066054, 14092.295385230902, 14653.267336632913, 12568.685782855136, 12842.0, 12940.352982350656, 13029.869701303245, 13096.148750331198, 12327.50689972391, 12792.74414511723, 12859.228446293253, 13445.991550633937, 13463.932680336535, 12637.368131593568, 12815.0, 12818.359082046047, 13271.535496257584, 13451.125676830974, 10557.838637749996, 11359.659210223706, 11395.14536409756, 11420.31478111316, 11523.711907202387, 11134.629977235538, 11138.331700097697, 11356.94321528387, 11359.716007099889, 11371.772564548497], "value": 6896.1721012786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 115.0, 118.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 116.0, 152.0, 116.0, 131.0, 114.0, 102.0, 166.0, 138.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 200.0, 196.0, 194.0, 193.0, 198.0, 195.0, 199.0, 199.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 86.0, 62.0, 59.0, 60.0, 60.0, 78.0, 59.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 89.0, 70.0, 70.0, 73.0, 114.0, 84.0, 58.0, 72.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 105.0, 87.0, 92.0, 86.0, 89.0, 87.0, 96.0, 86.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 134.0, 130.0, 101.0, 124.0, 146.0, 104.0], "value": 112.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 132.0, 137.0, 90.0, 70.0, 144.0, 99.0, 90.0, 76.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 56.0, 62.0, 60.0, 62.0, 61.0, 60.0, 61.0, 61.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 63.0, 73.0, 84.0, 78.0, 76.0, 70.0, 71.0, 71.0, 86.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 73.0, 91.0, 76.0, 98.0, 74.0, 94.0, 103.0, 102.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 152.0, 149.0, 140.0, 160.0, 160.0, 158.0, 178.0, 220.0, 168.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 170.0, 164.0, 171.0, 166.0, 206.0, 224.0, 165.0, 224.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 67.0, 69.0, 68.0, 69.0, 69.0, 66.0, 68.0, 66.0], "value": 68.0, "unit": "score"}]}]} 06:49:01 INFO - Return code: 0 06:49:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:49:01 ERROR - # TBPL SUCCESS # 06:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:01 INFO - 2016-12-29 06:49:01.937 screenresolution[2223:28368] starting screenresolution argv=screenresolution get 06:49:01 INFO - 2016-12-29 06:49:01.940 screenresolution[2223:28368] Display 0: 1600x1200x32@60 06:49:01 INFO - 2016-12-29 06:49:01.946 screenresolution[2224:28371] starting screenresolution argv=screenresolution list 06:49:01 INFO - Available Modes on Display 0 06:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:02 INFO - Intel Iris: 06:49:02 INFO - Chipset Model: Intel Iris 06:49:02 INFO - Type: GPU 06:49:02 INFO - Bus: Built-In 06:49:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:02 INFO - Vendor: Intel (0x8086) 06:49:02 INFO - Device ID: 0x0a2e 06:49:02 INFO - Revision ID: 0x0009 06:49:02 INFO - Displays: 06:49:02 INFO - Display: 06:49:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:02 INFO - Display Serial Number: 1600x1200 60 06:49:02 INFO - Main Display: Yes 06:49:02 INFO - Mirror: Off 06:49:02 INFO - Online: Yes 06:49:02 INFO - Rotation: Supported 06:49:02 INFO - Return code: 0 06:49:02 INFO - Running post-action listener: _resource_record_post_action 06:49:02 INFO - [mozharness: 2016-12-29 14:49:02.185369Z] Finished run-tests step (success) 06:49:02 INFO - Running post-run listener: _resource_record_post_run 06:49:02 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 1095168; Write bytes: 657502208; Read time: 20; Write time: 2048 06:49:02 INFO - TinderboxPrint: CPU usage
54.4% 06:49:02 INFO - TinderboxPrint: I/O read bytes / time
1,095,168 / 20 06:49:02 INFO - TinderboxPrint: I/O write bytes / time
657,502,208 / 2,048 06:49:02 INFO - TinderboxPrint: CPU idle
2,205.4 (45.6%) 06:49:02 INFO - TinderboxPrint: CPU system
972.4 (20.1%) 06:49:02 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 06:49:02 INFO - TinderboxPrint: Swap in / out
349,757,440 / 0 06:49:02 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 338539008; Write bytes: 334628352; Read time: 26719; Write time: 648 06:49:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1095168; Write bytes: 305179136; Read time: 20; Write time: 1347 06:49:02 INFO - Running post-run listener: _upload_blobber_files 06:49:02 INFO - Blob upload gear active. 06:49:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:49:02 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 06:49:02 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'] 06:49:02 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 06:49:03 INFO - (blobuploader) - INFO - Open directory for files ... 06:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:49:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:04 INFO - (blobuploader) - INFO - Done attempting. 06:49:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:49:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:49:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:05 INFO - (blobuploader) - INFO - Done attempting. 06:49:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:49:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:07 INFO - (blobuploader) - INFO - Done attempting. 06:49:07 INFO - (blobuploader) - INFO - Iteration through files over. 06:49:07 INFO - Return code: 0 06:49:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:49:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:49:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8deed3ece301546049c9bab80bcbe3e1fb78f1bf383008af9ff29fbb1ddcaf7d0b6c31c31106dbaeddca28ebdb049626b335db6b71aa8c598de6d3cbb2a7d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8accad3e306af53d66eacedfa08231cec030679462782b26d955eb8c413beb3c3b4edb0061feba2bddbbb3fd66b4dbac44e8628748760ffa52c8521a6dcda637", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/887cc56ef83331a9d5e127bef9c126d44e5a542600efd35ba533486e5b1dcc9b942568d4f04678e4ddccdb296399cbe719e60e45a2b7c7f2308a90ff01687d52"} 06:49:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:49:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:49:07 INFO - Contents: 06:49:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8deed3ece301546049c9bab80bcbe3e1fb78f1bf383008af9ff29fbb1ddcaf7d0b6c31c31106dbaeddca28ebdb049626b335db6b71aa8c598de6d3cbb2a7d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8accad3e306af53d66eacedfa08231cec030679462782b26d955eb8c413beb3c3b4edb0061feba2bddbbb3fd66b4dbac44e8628748760ffa52c8521a6dcda637", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/887cc56ef83331a9d5e127bef9c126d44e5a542600efd35ba533486e5b1dcc9b942568d4f04678e4ddccdb296399cbe719e60e45a2b7c7f2308a90ff01687d52"} 06:49:07 INFO - Running post-run listener: copy_logs_to_upload_dir 06:49:07 INFO - Copying logs to upload dir... 06:49:07 INFO - mkdir: /builds/slave/test/build/upload/logs 06:49:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.054410 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-29 06:49:07.485693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:07.488999) ========= 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.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/d8deed3ece301546049c9bab80bcbe3e1fb78f1bf383008af9ff29fbb1ddcaf7d0b6c31c31106dbaeddca28ebdb049626b335db6b71aa8c598de6d3cbb2a7d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8accad3e306af53d66eacedfa08231cec030679462782b26d955eb8c413beb3c3b4edb0061feba2bddbbb3fd66b4dbac44e8628748760ffa52c8521a6dcda637", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/887cc56ef83331a9d5e127bef9c126d44e5a542600efd35ba533486e5b1dcc9b942568d4f04678e4ddccdb296399cbe719e60e45a2b7c7f2308a90ff01687d52"} build_url:https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/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/d8deed3ece301546049c9bab80bcbe3e1fb78f1bf383008af9ff29fbb1ddcaf7d0b6c31c31106dbaeddca28ebdb049626b335db6b71aa8c598de6d3cbb2a7d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8accad3e306af53d66eacedfa08231cec030679462782b26d955eb8c413beb3c3b4edb0061feba2bddbbb3fd66b4dbac44e8628748760ffa52c8521a6dcda637", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/887cc56ef83331a9d5e127bef9c126d44e5a542600efd35ba533486e5b1dcc9b942568d4f04678e4ddccdb296399cbe719e60e45a2b7c7f2308a90ff01687d52"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:07.531908) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:07.532253) ========= 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.CQDMFTshXi/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.83i5ymYflx/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005274 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:07.581461) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 06:49:07.584906) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 06:49:07.585308) ========= ========= Total master_lag: 0.22 =========