builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0058 starttime: 1475273220.73 results: success (0) buildid: 20160930130919 builduid: 651f021ee29b45ef8f10ca89bd9f40a7 revision: 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.726062) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.726778) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.727365) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.753689) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.754251) ========= 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.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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-09-30 15:07:00-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-09-30 15:07:00 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135526 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.915877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:00.916250) ========= 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.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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.028927 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:01.065278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:01.065880) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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-09-30 15:07:01,138 truncating revision to first 12 chars 2016-09-30 15:07:01,139 Setting DEBUG logging. 2016-09-30 15:07:01,139 attempt 1/10 2016-09-30 15:07:01,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/38c9ddd81b65?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 15:07:01,809 unpacking tar archive at: autoland-38c9ddd81b65/testing/mozharness/ program finished with exit code 0 elapsedTime=0.954581 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:02.046351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:02.047203) ========= script_repo_revision: 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:02.048130) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:02.048781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 15:07:02.067918) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-30 15:07:02.068234) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:07:02 INFO - MultiFileLogger online at 20160930 15:07:02 in /builds/slave/test 15:07:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:07:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:07:02 INFO - {'append_to_log': False, 15:07:02 INFO - 'base_work_dir': '/builds/slave/test', 15:07:02 INFO - 'blob_upload_branch': 'Autoland', 15:07:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:07:02 INFO - 'branch': 'Autoland', 15:07:02 INFO - 'buildbot_json_path': 'buildprops.json', 15:07:02 INFO - 'config_files': ('talos/mac_config.py',), 15:07:02 INFO - 'default_actions': ('clobber', 15:07:02 INFO - 'read-buildbot-config', 15:07:02 INFO - 'download-and-extract', 15:07:02 INFO - 'populate-webroot', 15:07:02 INFO - 'create-virtualenv', 15:07:02 INFO - 'install', 15:07:02 INFO - 'run-tests'), 15:07:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:07:02 INFO - 'download_minidump_stackwalk': True, 15:07:02 INFO - 'download_symbols': 'ondemand', 15:07:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:07:02 INFO - 'tooltool.py': '/tools/tooltool.py', 15:07:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:07:02 INFO - '/tools/misc-python/virtualenv.py')}, 15:07:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:07:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:07:02 INFO - 'installer_path': 'installer.exe', 15:07:02 INFO - 'log_level': 'info', 15:07:02 INFO - 'log_name': 'talos', 15:07:02 INFO - 'log_to_console': True, 15:07:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:07:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:07:02 INFO - 'opt_config_files': (), 15:07:02 INFO - 'pip_index': False, 15:07:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:07:02 INFO - 'cmd': ('bash', 15:07:02 INFO - '-c', 15:07:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:07:02 INFO - 'enabled': True, 15:07:02 INFO - 'halt_on_failure': False, 15:07:02 INFO - 'name': 'check_screen_resolution'},), 15:07:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:07:02 INFO - 'cmd': ('bash', 15:07:02 INFO - '-c', 15:07:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:07:02 INFO - 'enabled': True, 15:07:02 INFO - 'halt_on_failure': False, 15:07:02 INFO - 'name': 'check_screen_resolution'},), 15:07:02 INFO - 'run_cmd_checks_enabled': True, 15:07:02 INFO - 'sps_profile': False, 15:07:02 INFO - 'sps_profile_interval': 0, 15:07:02 INFO - 'suite': 'dromaeojs', 15:07:02 INFO - 'system_bits': '32', 15:07:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:07:02 INFO - 'title': 't-yosemite-r7-0058', 15:07:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:07:02 INFO - 'use_talos_json': True, 15:07:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:07:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:07:02 INFO - 'work_dir': 'build'} 15:07:02 INFO - [mozharness: 2016-09-30 22:07:02.269769Z] Running clobber step. 15:07:02 INFO - Running pre-action listener: _resource_record_pre_action 15:07:02 INFO - Running main action method: clobber 15:07:02 INFO - rmtree: /builds/slave/test/build 15:07:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:07:04 INFO - Running post-action listener: _resource_record_post_action 15:07:04 INFO - [mozharness: 2016-09-30 22:07:04.675661Z] Finished clobber step (success) 15:07:04 INFO - [mozharness: 2016-09-30 22:07:04.675789Z] Running read-buildbot-config step. 15:07:04 INFO - Running pre-action listener: _resource_record_pre_action 15:07:04 INFO - Running main action method: read_buildbot_config 15:07:04 INFO - Using buildbot properties: 15:07:04 INFO - { 15:07:04 INFO - "project": "", 15:07:04 INFO - "product": "firefox", 15:07:04 INFO - "script_repo_revision": "production", 15:07:04 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:07:04 INFO - "repository": "", 15:07:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:07:04 INFO - "buildid": "20160930130919", 15:07:04 INFO - "pgo_build": "False", 15:07:04 INFO - "basedir": "/builds/slave/test", 15:07:04 INFO - "buildnumber": 263, 15:07:04 INFO - "slavename": "t-yosemite-r7-0058", 15:07:04 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:07:04 INFO - "platform": "yosemite_r7", 15:07:04 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:07:04 INFO - "branch": "autoland", 15:07:04 INFO - "repo_path": "integration/autoland", 15:07:04 INFO - "revision": "38c9ddd81b65f9141b1471266fc3d71ebc4e47c9", 15:07:04 INFO - "stage_platform": "macosx64", 15:07:04 INFO - "builduid": "651f021ee29b45ef8f10ca89bd9f40a7", 15:07:04 INFO - "slavebuilddir": "test" 15:07:04 INFO - } 15:07:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:07:04 INFO - Running post-action listener: _resource_record_post_action 15:07:04 INFO - [mozharness: 2016-09-30 22:07:04.680577Z] Finished read-buildbot-config step (success) 15:07:04 INFO - [mozharness: 2016-09-30 22:07:04.680706Z] Running download-and-extract step. 15:07:04 INFO - Running pre-action listener: _resource_record_pre_action 15:07:04 INFO - Running main action method: download_and_extract 15:07:04 INFO - mkdir: /builds/slave/test/build/tests 15:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:04 INFO - trying https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:07:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:07:05 INFO - Downloaded 1199 bytes. 15:07:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:07:05 INFO - Using the following test package requirements: 15:07:05 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:07:05 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:07:05 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:07:05 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'jsshell-mac.zip'], 15:07:05 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:07:05 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:07:05 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:07:05 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:07:05 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:07:05 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:07:05 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:07:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:07:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:07:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:07:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:07:06 INFO - Http code: 200 15:07:06 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 15:07:06 INFO - x-amz-cf-id: zDOdxoGiYbezNhUdL0c6KibTdqinHt9AoUpwf7HKbbzglzLpBCVBGw== 15:07:06 INFO - x-amz-version-id: eMbAqf7W8lfm36Rk8KUGZVL43P4eP7Ps 15:07:07 INFO - Expected file size: 16473489 15:07:07 INFO - Obtained file size: 16473489 15:07:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:07:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:07:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:07:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:07:14 INFO - Http code: 200 15:07:14 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 15:07:14 INFO - x-amz-cf-id: RLY4N8NYyuMH-XaGcInzIMzPH8x9krtwglVP81w4GRiGxP_Q83nVZQ== 15:07:14 INFO - x-amz-version-id: eMbAqf7W8lfm36Rk8KUGZVL43P4eP7Ps 15:07:14 INFO - Expected file size: 16473489 15:07:14 INFO - Obtained file size: 16473489 15:07:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:07:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:07:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:07:22 INFO - Http code: 200 15:07:22 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 15:07:22 INFO - x-amz-cf-id: P_lLCQOELv08i9MjAm8qV2DXTfdT2QcvuRQD4CMlvjEPS81lo0GgiA== 15:07:22 INFO - x-amz-version-id: Cjcvk_Eg.Cp8SSSCHpZIoGOFDYnBWQpI 15:07:23 INFO - Expected file size: 14408522 15:07:23 INFO - Obtained file size: 14408522 15:07: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')]} 15:07:23 INFO - trying https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:07:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:07:26 INFO - Downloaded 111778052 bytes. 15:07:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:07:26 INFO - mkdir: /builds/slave/test/properties 15:07:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:07:26 INFO - Writing to file /builds/slave/test/properties/build_url 15:07:26 INFO - Contents: 15:07:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:07:28 INFO - Running post-action listener: _resource_record_post_action 15:07:28 INFO - Running post-action listener: set_extra_try_arguments 15:07:28 INFO - [mozharness: 2016-09-30 22:07:28.003510Z] Finished download-and-extract step (success) 15:07:28 INFO - [mozharness: 2016-09-30 22:07:28.003653Z] Running populate-webroot step. 15:07:28 INFO - Running pre-action listener: _resource_record_pre_action 15:07:28 INFO - Running main action method: populate_webroot 15:07:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:07:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:07:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:07:28 INFO - '--tppagecycles', 15:07:28 INFO - '7'], 15:07:28 INFO - 'tests': ['tsvgm']}}, 15:07:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:07:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:07:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:07:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:07:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['damp', 'tps']}, 15:07:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'tests': ['damp', 'tps']}, 15:07:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['dromaeo_dom']}, 15:07:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:07:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['basic_compositor_video']}, 15:07:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:07:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'other-e10s': {'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['a11yr', 15:07:28 INFO - 'ts_paint', 15:07:28 INFO - 'tpaint', 15:07:28 INFO - 'sessionrestore', 15:07:28 INFO - 'sessionrestore_no_auto_restore', 15:07:28 INFO - 'tabpaint']}, 15:07:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['tsvgx', 15:07:28 INFO - 'tsvgr_opacity', 15:07:28 INFO - 'tart', 15:07:28 INFO - 'tscrollx', 15:07:28 INFO - 'cart']}, 15:07:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:07:28 INFO - 'tsvgr_opacity', 15:07:28 INFO - 'tart', 15:07:28 INFO - 'tscrollx', 15:07:28 INFO - 'cart']}, 15:07:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'talos_options': ['--disable-e10s'], 15:07:28 INFO - 'tests': ['tp5o']}, 15:07:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'tests': ['tp5o']}, 15:07:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'talos_options': ['--disable-e10s', 15:07:28 INFO - '--xperf_path', 15:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:28 INFO - 'tests': ['tp5n']}, 15:07:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:28 INFO - 'talos_options': ['--xperf_path', 15:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:28 INFO - 'tests': ['tp5n']}}, 15:07:28 INFO - 'talos.zip': {'path': '', 15:07:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:07:28 INFO - Running post-action listener: _resource_record_post_action 15:07:28 INFO - [mozharness: 2016-09-30 22:07:28.022323Z] Finished populate-webroot step (success) 15:07:28 INFO - [mozharness: 2016-09-30 22:07:28.022454Z] Running create-virtualenv step. 15:07:28 INFO - Running pre-action listener: _resource_record_pre_action 15:07:28 INFO - Running main action method: create_virtualenv 15:07:28 INFO - Creating virtualenv /builds/slave/test/build/venv 15:07:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:07:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:07:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:07:28 INFO - Using real prefix '/tools/python27' 15:07:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:07:28 INFO - Installing distribute.............................................................................................................................................................................................done. 15:07:31 INFO - Installing pip.................done. 15:07:31 INFO - Return code: 0 15:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:07:32 INFO - Reading from file tmpfile_stdout 15:07:32 INFO - Output received: 15:07:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:07:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07: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', 'pip>=1.5'] in /builds/slave/test/build 15:07: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 pip>=1.5 15:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:32 INFO - 'HOME': '/Users/cltbld', 15:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:32 INFO - 'LOGNAME': 'cltbld', 15:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:32 INFO - 'MOZ_NO_REMOTE': '1', 15:07:32 INFO - 'NO_EM_RESTART': '1', 15:07:32 INFO - 'PAGER': '/bin/cat', 15:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:32 INFO - 'PWD': '/builds/slave/test', 15:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:32 INFO - 'SHELL': '/bin/bash', 15:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:32 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:32 INFO - 'USER': 'cltbld', 15:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:32 INFO - 'XPC_FLAGS': '0x0', 15:07:32 INFO - 'XPC_SERVICE_NAME': '0', 15:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:07:32 INFO - Cleaning up... 15:07:32 INFO - Return code: 0 15:07:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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', '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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:07: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 psutil>=3.1.1 15:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:32 INFO - 'HOME': '/Users/cltbld', 15:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:32 INFO - 'LOGNAME': 'cltbld', 15:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:32 INFO - 'MOZ_NO_REMOTE': '1', 15:07:32 INFO - 'NO_EM_RESTART': '1', 15:07:32 INFO - 'PAGER': '/bin/cat', 15:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:32 INFO - 'PWD': '/builds/slave/test', 15:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:32 INFO - 'SHELL': '/bin/bash', 15:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:32 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:32 INFO - 'USER': 'cltbld', 15:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:32 INFO - 'XPC_FLAGS': '0x0', 15:07:32 INFO - 'XPC_SERVICE_NAME': '0', 15:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:32 INFO - Downloading/unpacking psutil>=3.1.1 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:07:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:35 INFO - Installing collected packages: psutil 15:07:35 INFO - Running setup.py install for psutil 15:07:36 INFO - building 'psutil._psutil_osx' extension 15:07:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:07:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:07:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:07:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:07:36 INFO - building 'psutil._psutil_posix' extension 15:07:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:07:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:07:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:07:36 INFO - ^ 15:07:36 INFO - 1 warning generated. 15:07:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:07:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:37 INFO - Successfully installed psutil 15:07:37 INFO - Cleaning up... 15:07:37 INFO - Return code: 0 15:07:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:37 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:37 INFO - 'HOME': '/Users/cltbld', 15:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:37 INFO - 'LOGNAME': 'cltbld', 15:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:37 INFO - 'MOZ_NO_REMOTE': '1', 15:07:37 INFO - 'NO_EM_RESTART': '1', 15:07:37 INFO - 'PAGER': '/bin/cat', 15:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:37 INFO - 'PWD': '/builds/slave/test', 15:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:37 INFO - 'SHELL': '/bin/bash', 15:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:37 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:37 INFO - 'USER': 'cltbld', 15:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:37 INFO - 'XPC_FLAGS': '0x0', 15:07:37 INFO - 'XPC_SERVICE_NAME': '0', 15:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:07:40 INFO - Installing collected packages: mozsystemmonitor 15:07:40 INFO - Running setup.py install for mozsystemmonitor 15:07:40 INFO - Successfully installed mozsystemmonitor 15:07:40 INFO - Cleaning up... 15:07:40 INFO - Return code: 0 15:07:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:40 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:40 INFO - 'HOME': '/Users/cltbld', 15:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:40 INFO - 'LOGNAME': 'cltbld', 15:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:40 INFO - 'MOZ_NO_REMOTE': '1', 15:07:40 INFO - 'NO_EM_RESTART': '1', 15:07:40 INFO - 'PAGER': '/bin/cat', 15:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:40 INFO - 'PWD': '/builds/slave/test', 15:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:40 INFO - 'SHELL': '/bin/bash', 15:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:40 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:40 INFO - 'USER': 'cltbld', 15:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:40 INFO - 'XPC_FLAGS': '0x0', 15:07:40 INFO - 'XPC_SERVICE_NAME': '0', 15:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:40 INFO - Downloading/unpacking jsonschema==2.5.1 15:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:07:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:07:43 INFO - warning: no files found matching '*.txt' 15:07:43 INFO - no previously-included directories found matching 'build' 15:07:43 INFO - no previously-included directories found matching 'dist' 15:07:43 INFO - no previously-included directories found matching '.git*' 15:07:43 INFO - Installing collected packages: jsonschema, functools32 15:07:43 INFO - Running setup.py install for functools32 15:07:43 INFO - warning: no files found matching '*.txt' 15:07:43 INFO - no previously-included directories found matching 'build' 15:07:43 INFO - no previously-included directories found matching 'dist' 15:07:43 INFO - no previously-included directories found matching '.git*' 15:07:43 INFO - Successfully installed jsonschema functools32 15:07:43 INFO - Cleaning up... 15:07:43 INFO - Return code: 0 15:07:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:43 INFO - 'HOME': '/Users/cltbld', 15:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:43 INFO - 'LOGNAME': 'cltbld', 15:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:43 INFO - 'MOZ_NO_REMOTE': '1', 15:07:43 INFO - 'NO_EM_RESTART': '1', 15:07:43 INFO - 'PAGER': '/bin/cat', 15:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:43 INFO - 'PWD': '/builds/slave/test', 15:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:43 INFO - 'SHELL': '/bin/bash', 15:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:43 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:43 INFO - 'USER': 'cltbld', 15:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:43 INFO - 'XPC_FLAGS': '0x0', 15:07:43 INFO - 'XPC_SERVICE_NAME': '0', 15:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:07:44 INFO - Cleaning up... 15:07:44 INFO - Return code: 0 15:07:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:44 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:07:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:44 INFO - 'HOME': '/Users/cltbld', 15:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:44 INFO - 'LOGNAME': 'cltbld', 15:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:44 INFO - 'MOZ_NO_REMOTE': '1', 15:07:44 INFO - 'NO_EM_RESTART': '1', 15:07:44 INFO - 'PAGER': '/bin/cat', 15:07:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:44 INFO - 'PWD': '/builds/slave/test', 15:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:44 INFO - 'SHELL': '/bin/bash', 15:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:44 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:44 INFO - 'USER': 'cltbld', 15:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:44 INFO - 'XPC_FLAGS': '0x0', 15:07:44 INFO - 'XPC_SERVICE_NAME': '0', 15:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:46 INFO - Downloading blobuploader-1.2.4.tar.gz 15:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:07:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:07:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:48 INFO - Downloading docopt-0.6.1.tar.gz 15:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:07:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:07:48 INFO - Running setup.py install for blobuploader 15:07:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:07:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:07:48 INFO - Running setup.py install for requests 15:07:48 INFO - Running setup.py install for docopt 15:07:48 INFO - Successfully installed blobuploader requests docopt 15:07:48 INFO - Cleaning up... 15:07:49 INFO - Return code: 0 15:07:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:49 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:49 INFO - 'HOME': '/Users/cltbld', 15:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:49 INFO - 'LOGNAME': 'cltbld', 15:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:49 INFO - 'MOZ_NO_REMOTE': '1', 15:07:49 INFO - 'NO_EM_RESTART': '1', 15:07:49 INFO - 'PAGER': '/bin/cat', 15:07:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:49 INFO - 'PWD': '/builds/slave/test', 15:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:49 INFO - 'SHELL': '/bin/bash', 15:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:49 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:49 INFO - 'USER': 'cltbld', 15:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:49 INFO - 'XPC_FLAGS': '0x0', 15:07:49 INFO - 'XPC_SERVICE_NAME': '0', 15:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-CTmptE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-YuMDnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-McnJYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-JZqL42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-TqU5rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-11DfDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:49 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-x_5Yp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-T5eSm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-N1vn2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-T2lYlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-Wl2_Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-Ny1KB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-BYtCEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-6AiNR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-3RAP5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:50 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-DXzQCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:51 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-TBpMks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:07:51 INFO - Running setup.py install for manifestparser 15:07:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:07:51 INFO - Running setup.py install for mozcrash 15:07:51 INFO - Running setup.py install for mozdebug 15:07:51 INFO - Running setup.py install for mozdevice 15:07:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:07:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:07:51 INFO - Running setup.py install for mozfile 15:07:51 INFO - Running setup.py install for mozhttpd 15:07:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:07:51 INFO - Running setup.py install for mozinfo 15:07:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Running setup.py install for mozInstall 15:07:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Running setup.py install for mozleak 15:07:52 INFO - Running setup.py install for mozlog 15:07:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Running setup.py install for moznetwork 15:07:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:07:52 INFO - Running setup.py install for mozprocess 15:07:52 INFO - Running setup.py install for mozprofile 15:07:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:07:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:07:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:07:53 INFO - Running setup.py install for mozrunner 15:07:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:07:53 INFO - Running setup.py install for mozscreenshot 15:07:53 INFO - Running setup.py install for moztest 15:07:53 INFO - Running setup.py install for mozversion 15:07:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:07:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:07:53 INFO - Cleaning up... 15:07:53 INFO - Return code: 0 15:07:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:53 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:53 INFO - 'HOME': '/Users/cltbld', 15:07:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:53 INFO - 'LOGNAME': 'cltbld', 15:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:53 INFO - 'MOZ_NO_REMOTE': '1', 15:07:53 INFO - 'NO_EM_RESTART': '1', 15:07:53 INFO - 'PAGER': '/bin/cat', 15:07:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:53 INFO - 'PWD': '/builds/slave/test', 15:07:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:53 INFO - 'SHELL': '/bin/bash', 15:07:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:53 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:53 INFO - 'USER': 'cltbld', 15:07:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:53 INFO - 'XPC_FLAGS': '0x0', 15:07:53 INFO - 'XPC_SERVICE_NAME': '0', 15:07:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:53 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-9CSd1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-anxKkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-a0pU66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-UQFhHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-dXe30m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-G1xOfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-dBoxRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-NYZRvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-3xKnvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-A1zlXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:54 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-AtSute-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-WobB0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-4Mcfl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-mVA96Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-47ZgvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-HpkRti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:55 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)) 15:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:55 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-CJDi8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:07:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:58 INFO - Downloading blessings-1.6.tar.gz 15:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:07:58 INFO - Installing collected packages: blessings 15:07:58 INFO - Running setup.py install for blessings 15:07:58 INFO - Successfully installed blessings 15:07:58 INFO - Cleaning up... 15:07:58 INFO - Return code: 0 15:07:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:07:58 INFO - Reading from file tmpfile_stdout 15:07:58 INFO - Current package versions: 15:07:58 INFO - blessings == 1.6 15:07:58 INFO - blobuploader == 1.2.4 15:07:58 INFO - docopt == 0.6.1 15:07:58 INFO - functools32 == 3.2.3-2 15:07:58 INFO - jsonschema == 2.5.1 15:07:58 INFO - manifestparser == 1.1 15:07:58 INFO - mozInstall == 1.12 15:07:58 INFO - mozcrash == 0.17 15:07:58 INFO - mozdebug == 0.1 15:07:58 INFO - mozdevice == 0.48 15:07:58 INFO - mozfile == 1.2 15:07:58 INFO - mozhttpd == 0.7 15:07:58 INFO - mozinfo == 0.9 15:07:58 INFO - mozleak == 0.1 15:07:58 INFO - mozlog == 3.4 15:07:58 INFO - moznetwork == 0.27 15:07:58 INFO - mozprocess == 0.23 15:07:58 INFO - mozprofile == 0.28 15:07:58 INFO - mozrunner == 6.12 15:07:58 INFO - mozscreenshot == 0.1 15:07:58 INFO - mozsystemmonitor == 0.3 15:07:58 INFO - moztest == 0.7 15:07:58 INFO - mozversion == 1.4 15:07:58 INFO - psutil == 3.1.1 15:07:58 INFO - requests == 1.2.3 15:07:58 INFO - wsgiref == 0.1.2 15:07:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:59 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 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:59 INFO - 'HOME': '/Users/cltbld', 15:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:59 INFO - 'LOGNAME': 'cltbld', 15:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:59 INFO - 'MOZ_NO_REMOTE': '1', 15:07:59 INFO - 'NO_EM_RESTART': '1', 15:07:59 INFO - 'PAGER': '/bin/cat', 15:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:59 INFO - 'PWD': '/builds/slave/test', 15:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:59 INFO - 'SHELL': '/bin/bash', 15:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:59 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:59 INFO - 'USER': 'cltbld', 15:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:59 INFO - 'XPC_FLAGS': '0x0', 15:07:59 INFO - 'XPC_SERVICE_NAME': '0', 15:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:07:59 INFO - Cleaning up... 15:07:59 INFO - Return code: 0 15:07:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c1a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b90570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe893595b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, '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.PjFQYLpe3A/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.QQXkmoe37V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:59 INFO - 'HOME': '/Users/cltbld', 15:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:59 INFO - 'LOGNAME': 'cltbld', 15:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:59 INFO - 'MOZ_NO_REMOTE': '1', 15:07:59 INFO - 'NO_EM_RESTART': '1', 15:07:59 INFO - 'PAGER': '/bin/cat', 15:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:59 INFO - 'PWD': '/builds/slave/test', 15:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:59 INFO - 'SHELL': '/bin/bash', 15:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:07:59 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:59 INFO - 'USER': 'cltbld', 15:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:59 INFO - 'XPC_FLAGS': '0x0', 15:07:59 INFO - 'XPC_SERVICE_NAME': '0', 15:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:08:00 INFO - Cleaning up... 15:08:00 INFO - Return code: 0 15:08:00 INFO - Running post-action listener: _resource_record_post_action 15:08:00 INFO - Running post-action listener: _start_resource_monitoring 15:08:00 INFO - Starting resource monitoring. 15:08:00 INFO - [mozharness: 2016-09-30 22:08:00.110606Z] Finished create-virtualenv step (success) 15:08:00 INFO - [mozharness: 2016-09-30 22:08:00.111589Z] Running install step. 15:08:00 INFO - Running pre-action listener: _resource_record_pre_action 15:08:00 INFO - Running main action method: install 15:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:08:00 INFO - Reading from file tmpfile_stdout 15:08:00 INFO - Detecting whether we're running mozinstall >=1.0... 15:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:08:00 INFO - Reading from file tmpfile_stdout 15:08:00 INFO - Output received: 15:08:00 INFO - Usage: mozinstall [options] installer 15:08:00 INFO - Options: 15:08:00 INFO - -h, --help show this help message and exit 15:08:00 INFO - -d DEST, --destination=DEST 15:08:00 INFO - Directory to install application into. [default: 15:08:00 INFO - "/builds/slave/test"] 15:08:00 INFO - --app=APP Application being installed. [default: firefox] 15:08:00 INFO - mkdir: /builds/slave/test/build/application 15:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:08:34 INFO - Reading from file tmpfile_stdout 15:08:34 INFO - Output received: 15:08:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:08:34 INFO - Running post-action listener: _resource_record_post_action 15:08:34 INFO - [mozharness: 2016-09-30 22:08:34.921509Z] Finished install step (success) 15:08:34 INFO - [mozharness: 2016-09-30 22:08:34.921676Z] Running run-tests step. 15:08:34 INFO - Running pre-action listener: _resource_record_pre_action 15:08:34 INFO - Running main action method: run_tests 15:08:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:08:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:08:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:08:34 INFO - 2016-09-30 15:08:34.935 screenresolution[1644:7671] starting screenresolution argv=screenresolution get 15:08:34 INFO - 2016-09-30 15:08:34.938 screenresolution[1644:7671] Display 0: 1600x1200x32@60 15:08:34 INFO - 2016-09-30 15:08:34.944 screenresolution[1645:7673] starting screenresolution argv=screenresolution list 15:08:34 INFO - Available Modes on Display 0 15:08:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:08:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:08:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:08:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:08:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:08:35 INFO - Intel Iris: 15:08:35 INFO - Chipset Model: Intel Iris 15:08:35 INFO - Type: GPU 15:08:35 INFO - Bus: Built-In 15:08:35 INFO - VRAM (Dynamic, Max): 1536 MB 15:08:35 INFO - Vendor: Intel (0x8086) 15:08:35 INFO - Device ID: 0x0a2e 15:08:35 INFO - Revision ID: 0x0009 15:08:35 INFO - Displays: 15:08:35 INFO - Display: 15:08:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:08:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:08:35 INFO - Display Serial Number: 1600x1200 60 15:08:35 INFO - Main Display: Yes 15:08:35 INFO - Mirror: Off 15:08:35 INFO - Online: Yes 15:08:35 INFO - Rotation: Supported 15:08:35 INFO - Return code: 0 15:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:08:35 INFO - Python 2.7.3 15:08:35 INFO - Return code: 0 15:08:35 INFO - grabbing minidump binary from tooltool 15:08: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')]} 15:08:35 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 0x107a2ed30>, '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 0x107854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a26b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:08:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:08:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:08:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:08:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:08:35 INFO - Return code: 0 15:08:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:08:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:08:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:08:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:08:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjFQYLpe3A/Render', 15:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:35 INFO - 'HOME': '/Users/cltbld', 15:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:35 INFO - 'LOGNAME': 'cltbld', 15:08:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:08:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:35 INFO - 'MOZ_NO_REMOTE': '1', 15:08:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:08:35 INFO - 'NO_EM_RESTART': '1', 15:08:35 INFO - 'PAGER': '/bin/cat', 15:08:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:35 INFO - 'PWD': '/builds/slave/test', 15:08:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:35 INFO - 'SHELL': '/bin/bash', 15:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQXkmoe37V/Listeners', 15:08:35 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 15:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:35 INFO - 'USER': 'cltbld', 15:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:35 INFO - 'XPC_FLAGS': '0x0', 15:08:35 INFO - 'XPC_SERVICE_NAME': '0', 15:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:08:35 INFO - mozversion application_buildid: 20160930130919 15:08:35 INFO - mozversion application_changeset: 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 15:08:35 INFO - mozversion application_display_name: Nightly 15:08:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:08:35 INFO - mozversion application_name: Firefox 15:08:35 INFO - mozversion application_remotingname: firefox 15:08:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:08:35 INFO - mozversion application_vendor: Mozilla 15:08:35 INFO - mozversion application_version: 52.0a1 15:08:35 INFO - mozversion platform_buildid: 20160930130919 15:08:35 INFO - mozversion platform_changeset: 38c9ddd81b65f9141b1471266fc3d71ebc4e47c9 15:08:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:08:35 INFO - mozversion platform_version: 52.0a1 15:08:35 INFO - using testdate: 1475273315 15:08:35 INFO - actual date: 1475273315 15:08:35 INFO - starting webserver on 'localhost:49273' 15:08:35 INFO - SUITE-START | Running 2 tests 15:08:35 INFO - TEST-START | dromaeo_css 15:08:35 INFO - operating with platform_type : mac_ 15:08:35 INFO - Initialising browser for dromaeo_css test... 15:08:35 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpfcxdvr/profile http://localhost:49273/getInfo.html) 15:08:38 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 15:08:38 INFO - PROCESS | 1655 | colorDepth:24 15:08:38 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 15:08:38 INFO - PROCESS | 1655 | __metrics 15:08:38 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:08:38 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:38 INFO - TEST-INFO | 1655: exit 0 15:08:38 INFO - Browser initialized. 15:08:38 INFO - Running cycle 1/1 for dromaeo_css test... 15:08:38 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpfcxdvr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:50 INFO - PROCESS | 1657 | RSS: Main: 235540480 15:08:50 INFO - PROCESS | 1657 | 15:09:42 INFO - PROCESS | 1657 | 1475273382327 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] 15:11:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 15:11:55 INFO - PROCESS | 1657 | RSS: Main: 393572352 15:11:55 INFO - PROCESS | 1657 | 15:14:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 15:14:59 INFO - PROCESS | 1657 | RSS: Main: 371159040 15:14:59 INFO - PROCESS | 1657 | 15:18:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 15:18:02 INFO - PROCESS | 1657 | RSS: Main: 347373568 15:18:02 INFO - PROCESS | 1657 | 15:21:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 15:21:05 INFO - PROCESS | 1657 | RSS: Main: 357789696 15:21:05 INFO - PROCESS | 1657 | 15:24:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 15:24:09 INFO - PROCESS | 1657 | RSS: Main: 332140544 15:24:09 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 15:27:12 INFO - PROCESS | 1657 | RSS: Main: 444882944 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | __start_tp_report 15:27:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 15:27:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 15:27:12 INFO - PROCESS | 1657 | |i|pagename|runs| 15:27:12 INFO - PROCESS | 1657 | |0;dojo.html;2507.836990595611;3292.357990191913;3306.2065104374956;3336.6830151135664;3363.1087761743142;5451.400345961951;5551;5666.51834594059;5681.687507187103;5691.260136182295;5516.393196748354;5552.611317207797;5641.12562552805;5641.830745077644;5649.745761440735;5062.063518249117;5079.161938280179;5081.8221362252325;5093.439721630618;5135.4094279158;5134.537891589756;5225.294585231002;5225.320708307915;5229.398619158807;5232.3197984262;5180.715060671661;5188.195829646411;5234.476552344773;5256.553192978601;5260.00059988601;5000.699958002531;5071.137906555894;5088.23676448532;5102.872428189288;5124.461931497213;5050.696958182484;5088.185890257541;5111.2333150027425;5118.206677964921;5155.175171972466;4744.4069491313585;5634.211210430543;5643.887122257532;5647.519960803295;5656.4060773618585;5310.973445132789;6172.969135154295;6200.3179650238435;6204.348543402968;6210.099535567362;5079.66982146163;6093.701951214651;6100.762843515328;6152.3540028297275;6224.159738435299;5747.770089196469;5772.105323674838;5789.9710501447225;5791.449812267828;5828.679422631753;6245.094461303085;6275.121482992385;6293.748250069946;6314.0844577536;6314.147590075373;5908.734106965197;5979.1629171915965;5982.7008649568215;6056.182415373956;6110.29731580865;5135.589152867761;6123.638900655339;6159.1069294951985;6168.228971378578;6226.501879849601;6112.655464441461;6147.477464415485;6177.938220617826;6181.629102253879;6188.535859810532;6131.621347939075;6195.473384762256;6212;6216.564840461125;6217.378262173746;4775.952240477551;4825.9865428260455;4838.395200599925;4889.608831293488;4926.965337279212;4265.4881414230495;4274;4284.657227421799;4292.441982542249;4372.213001659668;5494.735374102171;5499.697516636544;5506.146547285177;5517.972410137923;5561.916571251434;5936.317323507852;6010.699465026732;6026.21659184312;6028.849278768066;6135.509159267248;5631.605787594911;5694.117411801095;5701.087825947829;5704.058830293038;5706.3152421709665;5271.578273738091;5343.385510666245;5373.113436283048;5377.112776391929;5418.566514678816;5487.204355368529;5985.832762611249;6274.2784579773;6276.4351208389635;6292.4651404629285;6194.845128871814;6210.596311239755;6216.067589861376;6261.812145635638;6264.718087685974;5968.552358573212;6098.664573448503;6217.440430361289;6249.406306400884;6253.155823963707;6155.4767844731905;6157.630542167484;6168.660723660062;6178.567500274938;6184.938150618436;6133.8466538335115;6139.785107521216;6192.659403732838;6204.875902482016;6233.75064997395;4592.402987611589;5859.58982871212;5938.138969849434;5938.198343223782;5965.701714914324;6064.484518815773;6073.787417440369;6079.361667025076;6088.269407671108;6123.6631985239455;5935.792247271324;5938.317093534299;5942.168096466412;5942.197803296673;5959.344472107978;5854.092615644582;5967.164596956516;6005.789797357072;6037.426444487766;6047.153398524122;5866.472017518444;5935.881282374415;5959.880802384015;5994.340622531431;5999.520038397005 15:27:12 INFO - PROCESS | 1657 | |1;ext.html;456.43174248061166;501.1005245584176;528.9153735402335;545.163174527101;552.1469329885326;30382.088537343887;32059.519107213382;32132.839335803288;32142.67857321426;32158.35683286333;32941;33547;41427.79286103573;42154.57845421545;42179.15641687164;9665.130138287554;9805.558749856238;9817.361871478332;9829.311948163631;9829.803403931917;9623.470709110996;9715.708528744113;9721.854172187388;9722.854157187612;9735.318527703064;8736.257418119467;8988.640454381817;9729.805403891918;11820.468078936468;12115.394230288495;12081.577144799934;12155.209911355822;12159.756804863942;12169.634910952642;12170.208936419105;10003.549840257207;11535;11667.883321166764;11748.29510229389;11759.412029398496;30425;30970.84514577413;31007.379852402824;32268.354632907445;39371.21257574832;2911.912642620725;3359.328134373135;3508;3586.2648157127987;3834.6740527055235;30462;30849.537256941163;31498.685013149803;31686.524702129485;31815;2832.4618322518654;3213.1163929919458;3259.41330560499;3299.6040475143136;3382.52644629752;388647;440946;446612;447121.7643911792;447287;32396.514052289236;46517.30224046642;56098;56491.71754141285;56632.584185395695;32988.67011329904;34272;34277.31445371079;34305.82847085749;34321.141971450415;14066.578002659937;14241.287935603386;14245.857541424453;14248;14264.144151350745;11984.64046078619;12055.336956467303;12071.215371000857;12087.335196564274;12093.939530302292;7177.7487787927175;7621.31408173267;7633.809154771064;7694.961525192338;7767.18444563324;3075.5386691996378;3128.374325134982;3209.903702888917;3284.638689744126;3292.7859689120132;920.9677661281823;929.0987741890356;940.5908429832908;948.6537413844035;1001.639409812467;28212.294692632848;28611.28471788222;28972;29195.41609167805;29454.410911782074;993.3046867192994;1069.8983596558314;1111.9388433636066;1123.612353737959;1174.2426135142784;12403.6278911631;14422.639434014234;14489.855101449062;15025.549233523012;15121.470748523749;678912;681934.0839549643;682090;683552.5822370857;689609;49100.75449622729;56237.156442653395;56587;56885.43114568884;56907.43092569104;1133.7562424078862;1247.4822948476462;1356.559118286541;1385.6397336692532;1387.3132799264226;29796.255093621967;30617.693823061483;30720.078397648107;30833.845830770704;31096.844515778183;47302.76348618373;47809.52190478051;47932.5206747928;47934.04131917412;48170.036599267114;470230;477406;589041;597925;605943;39860.60139398685;39968.60031399649;40209.97375328084;40952;41170.9707257321;44537;45192.54807451883;45299.07266228463;45547.316790248835;45986.08027839357;36899.26201476009;36922;37026.07434814151;37338.25323493569;38043.42934856035;37117.25765484621;37382.439263410524;37624.89209917963;38437.80781096077;38476.03809904663;32192.19519512031;36376.63623363733;37200;37425;37454.81272593619;27082.864585676947;31740.682593173773;32947.34105317928;33125.503117452776;33293.83353083219;19370.709439358703;24403.790261575996;24495.020199192546;24539.38651533726;25263 15:27:12 INFO - PROCESS | 1657 | |2;jquery.html;356.714628297362;369.17857766469604;376.66853169211106;384.0839597559819;409.0999800439033;14962.925185374072;15303.770443443344;19773.802261977395;21019.36941891742;21384;20444.386668399933;20453.48866278344;20484.795152048475;21170.470738231554;21344.039518221678;5869.413058694139;6260.8121756347155;6261.812145635638;6268.623882567038;6276.780312689057;4263.189993901165;4434.9334759978465;4484.058347746977;6237.625742455467;6900.378965893068;6918.169819621652;6921.100256966587;6923.792286231421;6935.410490108322;6957.582545047313;6499.675016249193;6569.441597464222;6597.769078082244;6599.241087274977;6614.834629134286;4989.825356112518;5556.666600003972;5697.629654072513;5721.7711291548285;5725.799597014126;25430.745692543023;25459.363515912064;25517.810273715786;25738.227853164433;25763.484730305478;2026.6554685703466;2433.3186707721866;2898.174020404175;3130;3233.8059716416856;10122.696319110439;10312.484375781256;14637.268136593128;14923.925380373028;15058.698826023417;1801.4235444657716;1822.863285253598;1849.9815001849943;1888.6789245828243;1897.4876783268578;91362;112580;112884.43557782241;113125.43437282843;114613;7959.801004974922;11464.140189485823;11548.267759836091;11576.36330001858;11708.590199342982;13043.152195107288;13134.474621015244;13426.731465370638;13980.231087290296;14022.579322620337;7934.96845410093;8050.235227653364;8150.510969341858;8301.916980830236;8447.704330348408;7140.6072666003865;8150;8186.058603260702;8295.58522073906;8322;4345;4442.333649952533;4479.977600111979;4524.16302983949;4524.389207457017;1867.7105048717472;1922.3379462124674;1923.855710821666;1924.3264857300112;1925.3068895197719;740.6555951482586;804.4971892567146;883.5140672630027;898.2275243290765;972.1250874213265;16869.240884159997;20658.380248592566;20964.16143354249;21604.744305287542;21685.457863553223;842.9241368276885;886.3485338276362;913.1142791492242;959.318883592643;1007.677543186188;10763.408012559228;11305.32168069902;11311.264767790068;11389.316641001566;11415.771684566262;503677;533895.6188724001;542873;567674;576272;121866.39066804787;126170.10744838834;127405;129678;132694;991.0733464210986;997.9550920208608;1000.0599436529639;1003.6537394598852;1023.1047833145997;18556.907215463838;18664.81335186685;18972.146253418905;19018.904905475385;19107.518414783302;52734;52867.73566132145;53376;53490.930181396936;53758.73120634372;74359.25640743523;74628.62685686536;75276.85977271303;75960.62019689866;77906.2209377899;5227.529522342856;7698.461107722405;7954.681812727426;7998.640061197377;8012.839743204987;10338.844917326096;12484.875151248734;12849.614511564669;12930.418131183931;12976.870231297566;10825.350478971286;10961;11210.215284929976;11289.322640641587;11305.547778089114;10755.677329680122;10850.29473084247;10899.128069754559;11185.32888026721;11194.776104478027;11140.885911408794;11150.665480035612;11431.42842857837;11578.305301681927;11583.536658533565;6669.899951500828;7846.058472983279;7904.407169462198;7970.362371010189;8067.031956165298;9192.380838585717;10034.397936123856;10359.067683908484;10873.891261087289;10941 15:27:12 INFO - PROCESS | 1657 | |3;mootools.html;1339.7320535892825;1519.6808670179262;1536.946206882759;1694.5848267174551;1749.090472954064;7786.4938778979395;8254.669813207473;8523.914760852389;8807.735767926972;8818.823623527525;8697.91302086979;8768.430052046613;8778.824423511542;8795.736127916172;8810.207081362676;2326.3835083702834;2364.1607229433475;2367.9408014799596;2369.9407514812096;2372.9169479068237;2299.4366380236866;2339.5203983183405;2342.1334106380664;2342.9648555271683;2346.3195673254822;1950.8439324854048;2314.849534780234;2354.9411264718433;2361.728401233854;2362.3267368799898;2304.8617082974993;2306.042992158261;2307.9423014424606;2311.722593288816;2313.9884300578387;2317.0963324303534;2318.9912388111225;2320.1995311617634;2322.7212734471805;2328.4178955261186;8701.129887011311;8845.98271198814;8846.911530884674;8850.468971861645;8865.024847266795;5140.794368225266;5150.536451719363;5334.986352592995;5348.786048558053;5386.165144402624;3469;3599.120703437266;3668.4680721295263;3678.393065144248;3679.411294192943;1047.9214058945608;1088.1403691084033;1091.4379094766202;1105.9612913547987;1109.9944500277447;11545.96086352224;11578.710532236677;11650.184487085991;11655.242409243372;11657.883421165765;3929.97820566649;4190.748555086643;4438.778061096932;4548.476925153584;4560.566746159176;9079.636814527345;9083.091690830865;9087.409318394282;9107.18035376806;9110.134537218953;5215.348081489814;5219.921701174486;5220.738963051832;5221.32122824038;5224.373075230997;4150.584941505826;4389.561043895585;4561.384213131251;4564.064366804797;4913.336699545587;2997.370552184022;3693.2244228711734;3727.9440808387894;3742.85028598853;3745.6628903398823;1131.8981291683788;1132.4960392625367;1133.6032388664066;1144.0733006264952;1144.9370284634258;768.4889548450329;860.6385318166385;894.7852515396388;905.35719639057;928.1831987850783;5622.522085622686;5680.971595141998;5862.267216597926;5928.110783382527;6052.12244224585;736.3961551527802;796.5579103597444;881.3081730841387;885.8272636835884;951.8572214167792;3852.8056302545738;3946.9034476923625;4024.1348110155873;4061.3704875744306;4078.979605102015;10599.947000264949;10819.296745711503;10991.505382257817;10991.505382257817;10997.725056873642;10929.234953553176;10930.344179349264;10949.726256843642;10950.671479855617;10952.616658417077;7729.729459468892;8211.05572859117;8344.374171937128;8361.790955226168;8377.413581049268;5825.621334613236;5971.134185543159;6000.639961602318;6061.818145455643;6065.181200537871;8355.83288334242;9630.181434578139;9888.950555247178;10013.148882345082;10046.995300469895;9324.300677449353;9800.31397802147;10162.560985363607;10218.540165692413;10365.63720269787;7005.544639598614;7248;7320.890186647098;7614.466987310835;7623.428242881695;8211.21993410625;8230;8242.711505097293;8317.584120793816;8353.749387518385;6245.469135123565;6383.808485745435;7314.122305323395;7427.25727427253;7464.141623713343;7202.96398518004;7320.084989376328;7320.560766354036;7330.083739532559;7379.8155046124275;6888.862222755617;7035.401990830836;7037.859242815218;7039.436845052281;7040.5071644984355;4808.9519104808505;4931.4328852181025;4973.975130124326;5010.123228435083;5133.409657889241;3802.4866643003948;4977.900441991213;5019.598432125348;5165.276861239505;5200.089984252816 15:27:12 INFO - PROCESS | 1657 | |4;prototype.html;289.2219928392622;295.9082684367846;305.68361745593313;309.24081380211567;314.3571396494169;3851.595582463843;4824.276358546215;5230.2416149658275;5261.631685781997;5286.471352864712;4887.511248875119;5205.973970130153;5226.24219488174;5242.606804489669;5255.318298144595;3807.4669546263544;3902.8434717026275;4004.8598299059395;4017.3973903914352;4034.596540345964;3326.9500957485657;3641.6722494975447;3761;4036.3249251560715;4041.6968727345566;3520.894373168809;3933.8033098345113;3982.4424580558743;3995.720299579031;4046.898827529306;3734.421164719473;3754.7371683982133;4007.639312461878;4020.2160578687167;4023.476947996771;3105.9750282406976;3312.3044160726276;3485.35520928629;3496.4930085137767;3525.5769307682986;4032.056598682978;4808.230683090724;5110.463401342842;5155.66488178271;5208.192730126836;4501.572350626685;4505.053938672882;4506.346579745951;4516.36770852081;4531.782434471496;4887.9246565755475;5104.2088476286235;5248.501392367719;5541.889162216733;5561.63849349791;3625.3111908737255;3727.4781530585738;3763.8118094095184;3767;3782.8865134046023;5466.644668096601;5631.114530973615;5735.254416925773;5768.913466298009;5772.797952071699;3174.6349169845303;3392.338493993697;3807.1053302473897;3821.312163810541;3831.9616803832164;3114.4549703801745;3641.5630124384716;3647.8905632831056;3872;3884.378499440076;4150.833966641361;4491.3487544306545;4494.10117976406;4500.054988452396;4572.268437050056;4442.045499979999;4716;5527.44725527444;5537.501624853782;5554.166874968787;1122.7698321844166;1168.5384273212035;1217.1723228204905;1277.7861032019619;1282.9422675979604;856.2850020233175;860.5654144656908;877.6138499060393;884.9955750221208;885.5395194498825;4600.907981840344;5209.895802083937;5296.841094767163;5299.364076310791;5303.36359636846;5467.289252397163;5472.425395333512;5737.598368114275;5740;5745.368009518949;5331.280277162611;5335.039692855245;5348.79652078287;5352;5399.514043736082;2755.793315501305;2922.4739546881765;2965.2438628149685;2991.820490770561;3008.6389633244153;4945.406551213878;4958.975205123951;5610.87782443513;5950.375210602911;6005.939940600625;5739.426057394227;5921.134842524591;5938.524918006549;5944.494717948936;5953.880922381615;5807.970960145172;5971.19388882504;5979.970100149559;5989.9101513477335;6042.425969532887;5785.392533783892;5786.739596718242;5805.042168042226;5805.506531944832;5857.179994800646;4973.900521989613;5687.260656114679;5702.37273899879;5726.942730572641;5744.10444553288;5664.612170018372;5949.881002380015;5950.166976623363;5964.343922168471;5980.551458640706;3242.578464799561;3807.314683357022;3825.6365645263654;3848.615138486241;3884.504465780751;5774.797882074107;5779.682117483579;5790.449907258803;5814.825555233349;5829.912551311819;5252.317198764136;5578.916316255179;5633.324001119728;5642.6614403135945;5796.478316951495;4653.418322709661;4875.658703890694;5597.63615365;5608.691521966168;5638.774449021993;4450.531324662788;5216.321878155815;5224.615476898549;5433.646812957145;5514.503694667499;5582.692951887685;5597.244372009889;5598.916016259678;5605.915911261416;5640.746166422438;5036.111458203174;5234.7906083756225;5486.396496385474;5489.212443414031;5496.477834605706 15:27:12 INFO - PROCESS | 1657 | |5;yui.html;704.4610872682398;713.5718568858682;732.8387754693965;766.6396793507054;840.1976112812263;11919.523219071238;15207.695846083072;15888.841111588881;15942.282597283122;15964.920175399122;13354.33228338584;13530.18818870866;13559.52541661042;13591.592252232424;13652.590422287323;683.4259222253306;704.1339152842007;851.7274472168908;864.6109250837117;866.6403442571357;696.5228818259483;839.1273076000982;869.3740506835101;881.6561540999015;884.4251236696134;914.7850255190058;916.4043371808311;918.1231923513062;919.4850883505259;924.2652091587204;707.2856415020866;912.4160537256162;914.1224424552438;914.7758799094198;916.4821875640242;550.018217481793;657.8782925158813;675.2201207605224;677.5392732941598;683.453237410075;14810.555683329518;14850.480233191787;14875.628109297355;14895.255237238203;14898.255087245703;13255.66860828487;13563.660908477268;13701.657458563515;13982.650433739136;14107.858921410758;12052.578159764365;12270.293446071977;12270.32513211773;12301.446434910362;12884.16252943565;4419.206397479279;4459.420275364214;4522;4576.336431217488;4678.1813182692895;112847;114453;115006.84993150044;115506;117250.41374793071;9620.615175392906;10768.623098191525;10839.512221950032;10842.457877106113;11181.32912025281;21086.894565527382;21090;21111.472213194793;21142.894285528473;21144.471388215417;7684.884726729104;7773.766786996399;7870.5270913398945;7879.093904200976;8014.71948481812;4276.764777937181;4386.780660967002;4406.3170208617075;4435.467743870691;4553.362529245908;3388.711959483422;3518.208403109331;3523.489094081344;3524.136586536315;3524.84138213781;562.2100948167831;648.7033372513469;661.2593894837812;667.0494545272967;668.7091115364832;555.2143716640908;600.3366280260336;603.2188316130533;611.7583554495949;641.8459569703331;9022.909770902206;9080.81838363229;9172.174504294646;9237.953810230905;10733.624323148651;584.231735268121;589.2100902227947;650.9804705858832;657.5101549345673;679.9116114905031;4281.529031806561;4311.525732169459;4436.334549817553;4459.2865141577195;4506;115889.4205528967;124854;133370.6657333555;142316.28841855933;148982;82940.17059829445;125913.7408625902;127995.36002319929;128423;129160;103726;105497.47251263694;106825.46587267169;107693.46153269183;108281.45859270653;8890.244329232088;8913.509756963429;8937.910620893708;8977.775555611162;9097.1812536872;58314.12528812156;58732;59572.70213648904;59668.70165649144;59953.10070349035;79823;87063;91976.54011729898;95429;98018;12887.871121288666;12983.610491685264;13189.472421103048;13678.794818077937;13715.177089374669;14801.481948131765;14840.40638374453;15072.020318679251;15094.245287735788;15256.008359457044;13794.310284485535;14049.719005620034;14250.144991300554;14263.928680356947;14455.349509271897;11805.586804461802;13961.232132232824;14198.432062717375;14274.571762847132;14362.998100580087;11789.41052947332;13094.738105237651;13409.69547782611;13834.93082534581;13928.094673846168;8328.791780205544;10828.837567436653;11212.27120237182;11450.427478626201;11683.539557555305;11323.32060076398;11490.82763758561;11698.824517632072;11798.587049453228;11990.580329688419 15:27:12 INFO - PROCESS | 1657 | __end_tp_report 15:27:12 INFO - PROCESS | 1657 | __start_cc_report 15:27:12 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1372 15:27:12 INFO - PROCESS | 1657 | __end_cc_report 15:27:12 INFO - PROCESS | 1657 | __startTimestamp1475274432680__endTimestamp 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | ------- Summary: start ------- 15:27:12 INFO - PROCESS | 1657 | Number of tests: 6 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5637.90 Median:5866.47 stddev:667.94 (11.4%) stddev-sans-first:623.23 15:27:12 INFO - PROCESS | 1657 | Values: 2507.8 3292.4 3306.2 3336.7 3363.1 5451.4 5551.0 5666.5 5681.7 5691.3 5516.4 5552.6 5641.1 5641.8 5649.7 5062.1 5079.2 5081.8 5093.4 5135.4 5134.5 5225.3 5225.3 5229.4 5232.3 5180.7 5188.2 5234.5 5256.6 5260.0 5000.7 5071.1 5088.2 5102.9 5124.5 5050.7 5088.2 5111.2 5118.2 5155.2 4744.4 5634.2 5643.9 5647.5 5656.4 5311.0 6173.0 6200.3 6204.3 6210.1 5079.7 6093.7 6100.8 6152.4 6224.2 5747.8 5772.1 5790.0 5791.4 5828.7 6245.1 6275.1 6293.7 6314.1 6314.1 5908.7 5979.2 5982.7 6056.2 6110.3 5135.6 6123.6 6159.1 6168.2 6226.5 6112.7 6147.5 6177.9 6181.6 6188.5 6131.6 6195.5 6212.0 6216.6 6217.4 4776.0 4826.0 4838.4 4889.6 4927.0 4265.5 4274.0 4284.7 4292.4 4372.2 5494.7 5499.7 5506.1 5518.0 5561.9 5936.3 6010.7 6026.2 6028.8 6135.5 5631.6 5694.1 5701.1 5704.1 5706.3 5271.6 5343.4 5373.1 5377.1 5418.6 5487.2 5985.8 6274.3 6276.4 6292.5 6194.8 6210.6 6216.1 6261.8 6264.7 5968.6 6098.7 6217.4 6249.4 6253.2 6155.5 6157.6 6168.7 6178.6 6184.9 6133.8 6139.8 6192.7 6204.9 6233.8 4592.4 5859.6 5938.1 5938.2 5965.7 6064.5 6073.8 6079.4 6088.3 6123.7 5935.8 5938.3 5942.2 5942.2 5959.3 5854.1 5967.2 6005.8 6037.4 6047.2 5866.5 5935.9 5959.9 5994.3 5999.5 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:67032.37 Median:34313.49 stddev:151727.46 (442.2%) stddev-sans-first:152070.76 15:27:12 INFO - PROCESS | 1657 | Values: 456.4 501.1 528.9 545.2 552.1 30382.1 32059.5 32132.8 32142.7 32158.4 32941.0 33547.0 41427.8 42154.6 42179.2 9665.1 9805.6 9817.4 9829.3 9829.8 9623.5 9715.7 9721.9 9722.9 9735.3 8736.3 8988.6 9729.8 11820.5 12115.4 12081.6 12155.2 12159.8 12169.6 12170.2 10003.5 11535.0 11667.9 11748.3 11759.4 30425.0 30970.8 31007.4 32268.4 39371.2 2911.9 3359.3 3508.0 3586.3 3834.7 30462.0 30849.5 31498.7 31686.5 31815.0 2832.5 3213.1 3259.4 3299.6 3382.5 388647.0 440946.0 446612.0 447121.8 447287.0 32396.5 46517.3 56098.0 56491.7 56632.6 32988.7 34272.0 34277.3 34305.8 34321.1 14066.6 14241.3 14245.9 14248.0 14264.1 11984.6 12055.3 12071.2 12087.3 12093.9 7177.7 7621.3 7633.8 7695.0 7767.2 3075.5 3128.4 3209.9 3284.6 3292.8 921.0 929.1 940.6 948.7 1001.6 28212.3 28611.3 28972.0 29195.4 29454.4 993.3 1069.9 1111.9 1123.6 1174.2 12403.6 14422.6 14489.9 15025.5 15121.5 678912.0 681934.1 682090.0 683552.6 689609.0 49100.8 56237.2 56587.0 56885.4 56907.4 1133.8 1247.5 1356.6 1385.6 1387.3 29796.3 30617.7 30720.1 30833.8 31096.8 47302.8 47809.5 47932.5 47934.0 48170.0 470230.0 477406.0 589041.0 597925.0 605943.0 39860.6 39968.6 40210.0 40952.0 41171.0 44537.0 45192.5 45299.1 45547.3 45986.1 36899.3 36922.0 37026.1 37338.3 38043.4 37117.3 37382.4 37624.9 38437.8 38476.0 32192.2 36376.6 37200.0 37425.0 37454.8 27082.9 31740.7 32947.3 33125.5 33293.8 19370.7 24403.8 24495.0 24539.4 25263.0 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33191.26 Median:25628.02 stddev:91327.56 (356.4%) stddev-sans-first:91550.49 15:27:12 INFO - PROCESS | 1657 | Values: 356.7 369.2 376.7 384.1 409.1 14962.9 15303.8 19773.8 21019.4 21384.0 20444.4 20453.5 20484.8 21170.5 21344.0 5869.4 6260.8 6261.8 6268.6 6276.8 4263.2 4434.9 4484.1 6237.6 6900.4 6918.2 6921.1 6923.8 6935.4 6957.6 6499.7 6569.4 6597.8 6599.2 6614.8 4989.8 5556.7 5697.6 5721.8 5725.8 25430.7 25459.4 25517.8 25738.2 25763.5 2026.7 2433.3 2898.2 3130.0 3233.8 10122.7 10312.5 14637.3 14923.9 15058.7 1801.4 1822.9 1850.0 1888.7 1897.5 91362.0 112580.0 112884.4 113125.4 114613.0 7959.8 11464.1 11548.3 11576.4 11708.6 13043.2 13134.5 13426.7 13980.2 14022.6 7935.0 8050.2 8150.5 8301.9 8447.7 7140.6 8150.0 8186.1 8295.6 8322.0 4345.0 4442.3 4480.0 4524.2 4524.4 1867.7 1922.3 1923.9 1924.3 1925.3 740.7 804.5 883.5 898.2 972.1 16869.2 20658.4 20964.2 21604.7 21685.5 842.9 886.3 913.1 959.3 1007.7 10763.4 11305.3 11311.3 11389.3 11415.8 503677.0 533895.6 542873.0 567674.0 576272.0 121866.4 126170.1 127405.0 129678.0 132694.0 991.1 998.0 1000.1 1003.7 1023.1 18556.9 18664.8 18972.1 19018.9 19107.5 52734.0 52867.7 53376.0 53490.9 53758.7 74359.3 74628.6 75276.9 75960.6 77906.2 5227.5 7698.5 7954.7 7998.6 8012.8 10338.8 12484.9 12849.6 12930.4 12976.9 10825.4 10961.0 11210.2 11289.3 11305.5 10755.7 10850.3 10899.1 11185.3 11194.8 11140.9 11150.7 11431.4 11578.3 11583.5 6669.9 7846.1 7904.4 7970.4 8067.0 9192.4 10034.4 10359.1 10873.9 10941.0 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5625.37 Median:5014.86 stddev:3188.45 (63.6%) stddev-sans-first:3181.13 15:27:12 INFO - PROCESS | 1657 | Values: 1339.7 1519.7 1536.9 1694.6 1749.1 7786.5 8254.7 8523.9 8807.7 8818.8 8697.9 8768.4 8778.8 8795.7 8810.2 2326.4 2364.2 2367.9 2369.9 2372.9 2299.4 2339.5 2342.1 2343.0 2346.3 1950.8 2314.8 2354.9 2361.7 2362.3 2304.9 2306.0 2307.9 2311.7 2314.0 2317.1 2319.0 2320.2 2322.7 2328.4 8701.1 8846.0 8846.9 8850.5 8865.0 5140.8 5150.5 5335.0 5348.8 5386.2 3469.0 3599.1 3668.5 3678.4 3679.4 1047.9 1088.1 1091.4 1106.0 1110.0 11546.0 11578.7 11650.2 11655.2 11657.9 3930.0 4190.7 4438.8 4548.5 4560.6 9079.6 9083.1 9087.4 9107.2 9110.1 5215.3 5219.9 5220.7 5221.3 5224.4 4150.6 4389.6 4561.4 4564.1 4913.3 2997.4 3693.2 3727.9 3742.9 3745.7 1131.9 1132.5 1133.6 1144.1 1144.9 768.5 860.6 894.8 905.4 928.2 5622.5 5681.0 5862.3 5928.1 6052.1 736.4 796.6 881.3 885.8 951.9 3852.8 3946.9 4024.1 4061.4 4079.0 10599.9 10819.3 10991.5 10991.5 10997.7 10929.2 10930.3 10949.7 10950.7 10952.6 7729.7 8211.1 8344.4 8361.8 8377.4 5825.6 5971.1 6000.6 6061.8 6065.2 8355.8 9630.2 9889.0 10013.1 10047.0 9324.3 9800.3 10162.6 10218.5 10365.6 7005.5 7248.0 7320.9 7614.5 7623.4 8211.2 8230.0 8242.7 8317.6 8353.7 6245.5 6383.8 7314.1 7427.3 7464.1 7203.0 7320.1 7320.6 7330.1 7379.8 6888.9 7035.4 7037.9 7039.4 7040.5 4809.0 4931.4 4974.0 5010.1 5133.4 3802.5 4977.9 5019.6 5165.3 5200.1 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4511.60 Median:5209.04 stddev:1427.00 (27.4%) stddev-sans-first:1395.37 15:27:12 INFO - PROCESS | 1657 | Values: 289.2 295.9 305.7 309.2 314.4 3851.6 4824.3 5230.2 5261.6 5286.5 4887.5 5206.0 5226.2 5242.6 5255.3 3807.5 3902.8 4004.9 4017.4 4034.6 3327.0 3641.7 3761.0 4036.3 4041.7 3520.9 3933.8 3982.4 3995.7 4046.9 3734.4 3754.7 4007.6 4020.2 4023.5 3106.0 3312.3 3485.4 3496.5 3525.6 4032.1 4808.2 5110.5 5155.7 5208.2 4501.6 4505.1 4506.3 4516.4 4531.8 4887.9 5104.2 5248.5 5541.9 5561.6 3625.3 3727.5 3763.8 3767.0 3782.9 5466.6 5631.1 5735.3 5768.9 5772.8 3174.6 3392.3 3807.1 3821.3 3832.0 3114.5 3641.6 3647.9 3872.0 3884.4 4150.8 4491.3 4494.1 4500.1 4572.3 4442.0 4716.0 5527.4 5537.5 5554.2 1122.8 1168.5 1217.2 1277.8 1282.9 856.3 860.6 877.6 885.0 885.5 4600.9 5209.9 5296.8 5299.4 5303.4 5467.3 5472.4 5737.6 5740.0 5745.4 5331.3 5335.0 5348.8 5352.0 5399.5 2755.8 2922.5 2965.2 2991.8 3008.6 4945.4 4959.0 5610.9 5950.4 6005.9 5739.4 5921.1 5938.5 5944.5 5953.9 5808.0 5971.2 5980.0 5989.9 6042.4 5785.4 5786.7 5805.0 5805.5 5857.2 4973.9 5687.3 5702.4 5726.9 5744.1 5664.6 5949.9 5950.2 5964.3 5980.6 3242.6 3807.3 3825.6 3848.6 3884.5 5774.8 5779.7 5790.4 5814.8 5829.9 5252.3 5578.9 5633.3 5642.7 5796.5 4653.4 4875.7 5597.6 5608.7 5638.8 4450.5 5216.3 5224.6 5433.6 5514.5 5582.7 5597.2 5598.9 5605.9 5640.7 5036.1 5234.8 5486.4 5489.2 5496.5 15:27:12 INFO - PROCESS | 1657 | 15:27:12 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24052.22 Median:3523.81 stddev:37681.76 (1069.3%) stddev-sans-first:37746.69 15:27:12 INFO - PROCESS | 1657 | Values: 704.5 713.6 732.8 766.6 840.2 11919.5 15207.7 15888.8 15942.3 15964.9 13354.3 13530.2 13559.5 13591.6 13652.6 683.4 704.1 851.7 864.6 866.6 696.5 839.1 869.4 881.7 884.4 914.8 916.4 918.1 919.5 924.3 707.3 912.4 914.1 914.8 916.5 550.0 657.9 675.2 677.5 683.5 14810.6 14850.5 14875.6 14895.3 14898.3 13255.7 13563.7 13701.7 13982.7 14107.9 12052.6 12270.3 12270.3 12301.4 12884.2 4419.2 4459.4 4522.0 4576.3 4678.2 112847.0 114453.0 115006.8 115506.0 117250.4 9620.6 10768.6 10839.5 10842.5 11181.3 21086.9 21090.0 21111.5 21142.9 21144.5 7684.9 7773.8 7870.5 7879.1 8014.7 4276.8 4386.8 4406.3 4435.5 4553.4 3388.7 3518.2 3523.5 3524.1 3524.8 562.2 648.7 661.3 667.0 668.7 555.2 600.3 603.2 611.8 641.8 9022.9 9080.8 9172.2 9238.0 10733.6 584.2 589.2 651.0 657.5 679.9 4281.5 4311.5 4436.3 4459.3 4506.0 115889.4 124854.0 133370.7 142316.3 148982.0 82940.2 125913.7 127995.4 128423.0 129160.0 103726.0 105497.5 106825.5 107693.5 108281.5 8890.2 8913.5 8937.9 8977.8 9097.2 58314.1 58732.0 59572.7 59668.7 59953.1 79823.0 87063.0 91976.5 95429.0 98018.0 12887.9 12983.6 13189.5 13678.8 13715.2 14801.5 14840.4 15072.0 15094.2 15256.0 13794.3 14049.7 14250.1 14263.9 14455.3 11805.6 13961.2 14198.4 14274.6 14363.0 11789.4 13094.7 13409.7 13834.9 13928.1 8328.8 10828.8 11212.3 11450.4 11683.5 11323.3 11490.8 11698.8 11798.6 11990.6 15:27:12 INFO - PROCESS | 1657 | -------- Summary: end -------- 15:27:12 INFO - PROCESS | 1657 | 15:27:13 INFO - TEST-INFO | 1657: exit 0 15:27:13 INFO - TEST-OK | dromaeo_css | took 1117858ms 15:27:13 INFO - TEST-START | kraken 15:27:13 INFO - operating with platform_type : mac_ 15:27:13 INFO - Initialising browser for kraken test... 15:27:13 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmphqSl3m/profile http://localhost:49273/getInfo.html) 15:27:15 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:27:15 INFO - PROCESS | 1663 | colorDepth:24 15:27:15 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:27:15 INFO - PROCESS | 1663 | __metrics 15:27:15 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:27:15 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:16 INFO - TEST-INFO | 1663: exit 0 15:27:16 INFO - Browser initialized. 15:27:16 INFO - Running cycle 1/1 for kraken test... 15:27:16 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmphqSl3m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:28 INFO - PROCESS | 1664 | RSS: Main: 236056576 15:27:28 INFO - PROCESS | 1664 | 15:27:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 15:27:32 INFO - PROCESS | 1664 | RSS: Main: 294518784 15:27:32 INFO - PROCESS | 1664 | 15:27:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 15:27:35 INFO - PROCESS | 1664 | RSS: Main: 293560320 15:27:35 INFO - PROCESS | 1664 | 15:27:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 15:27:39 INFO - PROCESS | 1664 | RSS: Main: 381374464 15:27:39 INFO - PROCESS | 1664 | 15:27:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 15:27:42 INFO - PROCESS | 1664 | RSS: Main: 285978624 15:27:42 INFO - PROCESS | 1664 | 15:27:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:27:45 INFO - PROCESS | 1664 | RSS: Main: 289550336 15:27:45 INFO - PROCESS | 1664 | 15:27:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 15:27:48 INFO - PROCESS | 1664 | RSS: Main: 327856128 15:27:48 INFO - PROCESS | 1664 | 15:27:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 15:27:52 INFO - PROCESS | 1664 | RSS: Main: 329052160 15:27:52 INFO - PROCESS | 1664 | 15:27:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 15:27:55 INFO - PROCESS | 1664 | RSS: Main: 328470528 15:27:55 INFO - PROCESS | 1664 | 15:27:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:27:57 INFO - PROCESS | 1664 | RSS: Main: 303644672 15:27:57 INFO - PROCESS | 1664 | 15:27:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:28:00 INFO - PROCESS | 1664 | RSS: Main: 305025024 15:28:00 INFO - PROCESS | 1664 | 15:28:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:28:03 INFO - PROCESS | 1664 | RSS: Main: 311242752 15:28:03 INFO - PROCESS | 1664 | 15:28:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:28:06 INFO - PROCESS | 1664 | RSS: Main: 318697472 15:28:06 INFO - PROCESS | 1664 | 15:28:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:28:10 INFO - PROCESS | 1664 | RSS: Main: 321245184 15:28:10 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 15:28:12 INFO - PROCESS | 1664 | RSS: Main: 364982272 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | __start_tp_report 15:28:12 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:28:12 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:28:12 INFO - PROCESS | 1664 | |i|pagename|runs| 15:28:12 INFO - PROCESS | 1664 | |0;ai-astar;113;112;113;113;112;120;166;156;113;113 15:28:12 INFO - PROCESS | 1664 | |1;audio-beat-detection;150;114;122;184;145;106;142;141;180;114 15:28:12 INFO - PROCESS | 1664 | |2;audio-dft;184;184;187;202;184;183;187;183;184;178 15:28:12 INFO - PROCESS | 1664 | |3;audio-fft;83;58;59;89;61;58;59;57;80;57 15:28:12 INFO - PROCESS | 1664 | |4;audio-oscillator;91;94;125;102;67;68;91;67;69;89 15:28:12 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;87;83;86;83;87;84;86;84;85;83 15:28:12 INFO - PROCESS | 1664 | |6;imaging-darkroom;91;103;94;94;86;129;152;103;93;92 15:28:12 INFO - PROCESS | 1664 | |7;imaging-desaturate;84;84;84;85;80;86;85;83;85;85 15:28:12 INFO - PROCESS | 1664 | |8;json-parse-financial;55;53;55;54;55;56;54;54;53;54 15:28:12 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;64;72;69;76;72;69;70;74;71;72 15:28:12 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;87;90;88;89;86;88;88;92;101;108 15:28:12 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;146;129;159;154;159;162;157;135;170;144 15:28:12 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;227;239;185;172;172;168;169;165;163;172 15:28:12 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;71;70;69;68;68;68;67;69;67;67 15:28:12 INFO - PROCESS | 1664 | __end_tp_report 15:28:12 INFO - PROCESS | 1664 | __start_cc_report 15:28:12 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2093 15:28:12 INFO - PROCESS | 1664 | __end_cc_report 15:28:12 INFO - PROCESS | 1664 | __startTimestamp1475274492547__endTimestamp 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:28:12 INFO - PROCESS | 1664 | Number of tests: 14 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:123.10 Median:113.00 stddev:20.25 (17.9%) stddev-sans-first:21.14 15:28:12 INFO - PROCESS | 1664 | Values: 113.0 112.0 113.0 113.0 112.0 120.0 166.0 156.0 113.0 113.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:143.50 stddev:26.87 (18.7%) stddev-sans-first:28.24 15:28:12 INFO - PROCESS | 1664 | Values: 150.0 114.0 122.0 184.0 145.0 106.0 142.0 141.0 180.0 114.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:185.60 Median:184.00 stddev:6.28 (3.4%) stddev-sans-first:6.63 15:28:12 INFO - PROCESS | 1664 | Values: 184.0 184.0 187.0 202.0 184.0 183.0 187.0 183.0 184.0 178.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.59 (21.0%) stddev-sans-first:11.78 15:28:12 INFO - PROCESS | 1664 | Values: 83.0 58.0 59.0 89.0 61.0 58.0 59.0 57.0 80.0 57.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:79.00 stddev:18.96 (24.0%) stddev-sans-first:20.03 15:28:12 INFO - PROCESS | 1664 | Values: 91.0 94.0 125.0 102.0 67.0 68.0 91.0 67.0 69.0 89.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:85.50 stddev:1.62 (1.9%) stddev-sans-first:1.51 15:28:12 INFO - PROCESS | 1664 | Values: 87.0 83.0 86.0 83.0 87.0 84.0 86.0 84.0 85.0 83.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:91.50 stddev:20.78 (22.7%) stddev-sans-first:21.53 15:28:12 INFO - PROCESS | 1664 | Values: 91.0 103.0 94.0 94.0 86.0 129.0 152.0 103.0 93.0 92.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:85.00 stddev:1.66 (2.0%) stddev-sans-first:1.76 15:28:12 INFO - PROCESS | 1664 | Values: 84.0 84.0 84.0 85.0 80.0 86.0 85.0 83.0 85.0 85.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:0.97 15:28:12 INFO - PROCESS | 1664 | Values: 55.0 53.0 55.0 54.0 55.0 56.0 54.0 54.0 53.0 54.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:3.25 (4.5%) stddev-sans-first:2.29 15:28:12 INFO - PROCESS | 1664 | Values: 64.0 72.0 69.0 76.0 72.0 69.0 70.0 74.0 71.0 72.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:88.00 stddev:7.13 (8.1%) stddev-sans-first:7.36 15:28:12 INFO - PROCESS | 1664 | Values: 87.0 90.0 88.0 89.0 86.0 88.0 88.0 92.0 101.0 108.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:158.00 stddev:12.76 (8.1%) stddev-sans-first:13.38 15:28:12 INFO - PROCESS | 1664 | Values: 146.0 129.0 159.0 154.0 159.0 162.0 157.0 135.0 170.0 144.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:172.00 stddev:27.05 (15.7%) stddev-sans-first:23.59 15:28:12 INFO - PROCESS | 1664 | Values: 227.0 239.0 185.0 172.0 172.0 168.0 169.0 165.0 163.0 172.0 15:28:12 INFO - PROCESS | 1664 | 15:28:12 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.35 (2.0%) stddev-sans-first:1.05 15:28:12 INFO - PROCESS | 1664 | Values: 71.0 70.0 69.0 68.0 68.0 68.0 67.0 69.0 67.0 67.0 15:28:12 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:28:12 INFO - PROCESS | 1664 | 15:28:13 INFO - TEST-INFO | 1664: exit 0 15:28:13 INFO - TEST-OK | kraken | took 60158ms 15:28:13 INFO - SUITE-END | took 1178s 15:28:13 INFO - Completed test suite (00:19:38) 15:28:13 INFO - javascript benchmark 15:28:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2507.836990595611, 3292.357990191913, 3306.2065104374956, 3336.6830151135664, 3363.1087761743142, 5451.400345961951, 5551.0, 5666.51834594059, 5681.687507187103, 5691.260136182295, 5516.393196748354, 5552.611317207797, 5641.12562552805, 5641.830745077644, 5649.745761440735, 5062.063518249117, 5079.161938280179, 5081.8221362252325, 5093.439721630618, 5135.4094279158, 5134.537891589756, 5225.294585231002, 5225.320708307915, 5229.398619158807, 5232.3197984262, 5180.715060671661, 5188.195829646411, 5234.476552344773, 5256.553192978601, 5260.00059988601, 5000.699958002531, 5071.137906555894, 5088.23676448532, 5102.872428189288, 5124.461931497213, 5050.696958182484, 5088.185890257541, 5111.2333150027425, 5118.206677964921, 5155.175171972466, 4744.4069491313585, 5634.211210430543, 5643.887122257532, 5647.519960803295, 5656.4060773618585, 5310.973445132789, 6172.969135154295, 6200.3179650238435, 6204.348543402968, 6210.099535567362, 5079.66982146163, 6093.701951214651, 6100.762843515328, 6152.3540028297275, 6224.159738435299, 5747.770089196469, 5772.105323674838, 5789.9710501447225, 5791.449812267828, 5828.679422631753, 6245.094461303085, 6275.121482992385, 6293.748250069946, 6314.0844577536, 6314.147590075373, 5908.734106965197, 5979.1629171915965, 5982.7008649568215, 6056.182415373956, 6110.29731580865, 5135.589152867761, 6123.638900655339, 6159.1069294951985, 6168.228971378578, 6226.501879849601, 6112.655464441461, 6147.477464415485, 6177.938220617826, 6181.629102253879, 6188.535859810532, 6131.621347939075, 6195.473384762256, 6212.0, 6216.564840461125, 6217.378262173746, 4775.952240477551, 4825.9865428260455, 4838.395200599925, 4889.608831293488, 4926.965337279212, 4265.4881414230495, 4274.0, 4284.657227421799, 4292.441982542249, 4372.213001659668, 5494.735374102171, 5499.697516636544, 5506.146547285177, 5517.972410137923, 5561.916571251434, 5936.317323507852, 6010.699465026732, 6026.21659184312, 6028.849278768066, 6135.509159267248, 5631.605787594911, 5694.117411801095, 5701.087825947829, 5704.058830293038, 5706.3152421709665, 5271.578273738091, 5343.385510666245, 5373.113436283048, 5377.112776391929, 5418.566514678816, 5487.204355368529, 5985.832762611249, 6274.2784579773, 6276.4351208389635, 6292.4651404629285, 6194.845128871814, 6210.596311239755, 6216.067589861376, 6261.812145635638, 6264.718087685974, 5968.552358573212, 6098.664573448503, 6217.440430361289, 6249.406306400884, 6253.155823963707, 6155.4767844731905, 6157.630542167484, 6168.660723660062, 6178.567500274938, 6184.938150618436, 6133.8466538335115, 6139.785107521216, 6192.659403732838, 6204.875902482016, 6233.75064997395, 4592.402987611589, 5859.58982871212, 5938.138969849434, 5938.198343223782, 5965.701714914324, 6064.484518815773, 6073.787417440369, 6079.361667025076, 6088.269407671108, 6123.6631985239455, 5935.792247271324, 5938.317093534299, 5942.168096466412, 5942.197803296673, 5959.344472107978, 5854.092615644582, 5967.164596956516, 6005.789797357072, 6037.426444487766, 6047.153398524122, 5866.472017518444, 5935.881282374415, 5959.880802384015, 5994.340622531431, 5999.520038397005], "value": 5593.754853846119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.43174248061166, 501.1005245584176, 528.9153735402335, 545.163174527101, 552.1469329885326, 30382.088537343887, 32059.519107213382, 32132.839335803288, 32142.67857321426, 32158.35683286333, 32941.0, 33547.0, 41427.79286103573, 42154.57845421545, 42179.15641687164, 9665.130138287554, 9805.558749856238, 9817.361871478332, 9829.311948163631, 9829.803403931917, 9623.470709110996, 9715.708528744113, 9721.854172187388, 9722.854157187612, 9735.318527703064, 8736.257418119467, 8988.640454381817, 9729.805403891918, 11820.468078936468, 12115.394230288495, 12081.577144799934, 12155.209911355822, 12159.756804863942, 12169.634910952642, 12170.208936419105, 10003.549840257207, 11535.0, 11667.883321166764, 11748.29510229389, 11759.412029398496, 30425.0, 30970.84514577413, 31007.379852402824, 32268.354632907445, 39371.21257574832, 2911.912642620725, 3359.328134373135, 3508.0, 3586.2648157127987, 3834.6740527055235, 30462.0, 30849.537256941163, 31498.685013149803, 31686.524702129485, 31815.0, 2832.4618322518654, 3213.1163929919458, 3259.41330560499, 3299.6040475143136, 3382.52644629752, 388647.0, 440946.0, 446612.0, 447121.7643911792, 447287.0, 32396.514052289236, 46517.30224046642, 56098.0, 56491.71754141285, 56632.584185395695, 32988.67011329904, 34272.0, 34277.31445371079, 34305.82847085749, 34321.141971450415, 14066.578002659937, 14241.287935603386, 14245.857541424453, 14248.0, 14264.144151350745, 11984.64046078619, 12055.336956467303, 12071.215371000857, 12087.335196564274, 12093.939530302292, 7177.7487787927175, 7621.31408173267, 7633.809154771064, 7694.961525192338, 7767.18444563324, 3075.5386691996378, 3128.374325134982, 3209.903702888917, 3284.638689744126, 3292.7859689120132, 920.9677661281823, 929.0987741890356, 940.5908429832908, 948.6537413844035, 1001.639409812467, 28212.294692632848, 28611.28471788222, 28972.0, 29195.41609167805, 29454.410911782074, 993.3046867192994, 1069.8983596558314, 1111.9388433636066, 1123.612353737959, 1174.2426135142784, 12403.6278911631, 14422.639434014234, 14489.855101449062, 15025.549233523012, 15121.470748523749, 678912.0, 681934.0839549643, 682090.0, 683552.5822370857, 689609.0, 49100.75449622729, 56237.156442653395, 56587.0, 56885.43114568884, 56907.43092569104, 1133.7562424078862, 1247.4822948476462, 1356.559118286541, 1385.6397336692532, 1387.3132799264226, 29796.255093621967, 30617.693823061483, 30720.078397648107, 30833.845830770704, 31096.844515778183, 47302.76348618373, 47809.52190478051, 47932.5206747928, 47934.04131917412, 48170.036599267114, 470230.0, 477406.0, 589041.0, 597925.0, 605943.0, 39860.60139398685, 39968.60031399649, 40209.97375328084, 40952.0, 41170.9707257321, 44537.0, 45192.54807451883, 45299.07266228463, 45547.316790248835, 45986.08027839357, 36899.26201476009, 36922.0, 37026.07434814151, 37338.25323493569, 38043.42934856035, 37117.25765484621, 37382.439263410524, 37624.89209917963, 38437.80781096077, 38476.03809904663, 32192.19519512031, 36376.63623363733, 37200.0, 37425.0, 37454.81272593619, 27082.864585676947, 31740.682593173773, 32947.34105317928, 33125.503117452776, 33293.83353083219, 19370.709439358703, 24403.790261575996, 24495.020199192546, 24539.38651533726, 25263.0], "value": 18404.630086562272, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.714628297362, 369.17857766469604, 376.66853169211106, 384.0839597559819, 409.0999800439033, 14962.925185374072, 15303.770443443344, 19773.802261977395, 21019.36941891742, 21384.0, 20444.386668399933, 20453.48866278344, 20484.795152048475, 21170.470738231554, 21344.039518221678, 5869.413058694139, 6260.8121756347155, 6261.812145635638, 6268.623882567038, 6276.780312689057, 4263.189993901165, 4434.9334759978465, 4484.058347746977, 6237.625742455467, 6900.378965893068, 6918.169819621652, 6921.100256966587, 6923.792286231421, 6935.410490108322, 6957.582545047313, 6499.675016249193, 6569.441597464222, 6597.769078082244, 6599.241087274977, 6614.834629134286, 4989.825356112518, 5556.666600003972, 5697.629654072513, 5721.7711291548285, 5725.799597014126, 25430.745692543023, 25459.363515912064, 25517.810273715786, 25738.227853164433, 25763.484730305478, 2026.6554685703466, 2433.3186707721866, 2898.174020404175, 3130.0, 3233.8059716416856, 10122.696319110439, 10312.484375781256, 14637.268136593128, 14923.925380373028, 15058.698826023417, 1801.4235444657716, 1822.863285253598, 1849.9815001849943, 1888.6789245828243, 1897.4876783268578, 91362.0, 112580.0, 112884.43557782241, 113125.43437282843, 114613.0, 7959.801004974922, 11464.140189485823, 11548.267759836091, 11576.36330001858, 11708.590199342982, 13043.152195107288, 13134.474621015244, 13426.731465370638, 13980.231087290296, 14022.579322620337, 7934.96845410093, 8050.235227653364, 8150.510969341858, 8301.916980830236, 8447.704330348408, 7140.6072666003865, 8150.0, 8186.058603260702, 8295.58522073906, 8322.0, 4345.0, 4442.333649952533, 4479.977600111979, 4524.16302983949, 4524.389207457017, 1867.7105048717472, 1922.3379462124674, 1923.855710821666, 1924.3264857300112, 1925.3068895197719, 740.6555951482586, 804.4971892567146, 883.5140672630027, 898.2275243290765, 972.1250874213265, 16869.240884159997, 20658.380248592566, 20964.16143354249, 21604.744305287542, 21685.457863553223, 842.9241368276885, 886.3485338276362, 913.1142791492242, 959.318883592643, 1007.677543186188, 10763.408012559228, 11305.32168069902, 11311.264767790068, 11389.316641001566, 11415.771684566262, 503677.0, 533895.6188724001, 542873.0, 567674.0, 576272.0, 121866.39066804787, 126170.10744838834, 127405.0, 129678.0, 132694.0, 991.0733464210986, 997.9550920208608, 1000.0599436529639, 1003.6537394598852, 1023.1047833145997, 18556.907215463838, 18664.81335186685, 18972.146253418905, 19018.904905475385, 19107.518414783302, 52734.0, 52867.73566132145, 53376.0, 53490.930181396936, 53758.73120634372, 74359.25640743523, 74628.62685686536, 75276.85977271303, 75960.62019689866, 77906.2209377899, 5227.529522342856, 7698.461107722405, 7954.681812727426, 7998.640061197377, 8012.839743204987, 10338.844917326096, 12484.875151248734, 12849.614511564669, 12930.418131183931, 12976.870231297566, 10825.350478971286, 10961.0, 11210.215284929976, 11289.322640641587, 11305.547778089114, 10755.677329680122, 10850.29473084247, 10899.128069754559, 11185.32888026721, 11194.776104478027, 11140.885911408794, 11150.665480035612, 11431.42842857837, 11578.305301681927, 11583.536658533565, 6669.899951500828, 7846.058472983279, 7904.407169462198, 7970.362371010189, 8067.031956165298, 9192.380838585717, 10034.397936123856, 10359.067683908484, 10873.891261087289, 10941.0], "value": 9526.975545276251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.7320535892825, 1519.6808670179262, 1536.946206882759, 1694.5848267174551, 1749.090472954064, 7786.4938778979395, 8254.669813207473, 8523.914760852389, 8807.735767926972, 8818.823623527525, 8697.91302086979, 8768.430052046613, 8778.824423511542, 8795.736127916172, 8810.207081362676, 2326.3835083702834, 2364.1607229433475, 2367.9408014799596, 2369.9407514812096, 2372.9169479068237, 2299.4366380236866, 2339.5203983183405, 2342.1334106380664, 2342.9648555271683, 2346.3195673254822, 1950.8439324854048, 2314.849534780234, 2354.9411264718433, 2361.728401233854, 2362.3267368799898, 2304.8617082974993, 2306.042992158261, 2307.9423014424606, 2311.722593288816, 2313.9884300578387, 2317.0963324303534, 2318.9912388111225, 2320.1995311617634, 2322.7212734471805, 2328.4178955261186, 8701.129887011311, 8845.98271198814, 8846.911530884674, 8850.468971861645, 8865.024847266795, 5140.794368225266, 5150.536451719363, 5334.986352592995, 5348.786048558053, 5386.165144402624, 3469.0, 3599.120703437266, 3668.4680721295263, 3678.393065144248, 3679.411294192943, 1047.9214058945608, 1088.1403691084033, 1091.4379094766202, 1105.9612913547987, 1109.9944500277447, 11545.96086352224, 11578.710532236677, 11650.184487085991, 11655.242409243372, 11657.883421165765, 3929.97820566649, 4190.748555086643, 4438.778061096932, 4548.476925153584, 4560.566746159176, 9079.636814527345, 9083.091690830865, 9087.409318394282, 9107.18035376806, 9110.134537218953, 5215.348081489814, 5219.921701174486, 5220.738963051832, 5221.32122824038, 5224.373075230997, 4150.584941505826, 4389.561043895585, 4561.384213131251, 4564.064366804797, 4913.336699545587, 2997.370552184022, 3693.2244228711734, 3727.9440808387894, 3742.85028598853, 3745.6628903398823, 1131.8981291683788, 1132.4960392625367, 1133.6032388664066, 1144.0733006264952, 1144.9370284634258, 768.4889548450329, 860.6385318166385, 894.7852515396388, 905.35719639057, 928.1831987850783, 5622.522085622686, 5680.971595141998, 5862.267216597926, 5928.110783382527, 6052.12244224585, 736.3961551527802, 796.5579103597444, 881.3081730841387, 885.8272636835884, 951.8572214167792, 3852.8056302545738, 3946.9034476923625, 4024.1348110155873, 4061.3704875744306, 4078.979605102015, 10599.947000264949, 10819.296745711503, 10991.505382257817, 10991.505382257817, 10997.725056873642, 10929.234953553176, 10930.344179349264, 10949.726256843642, 10950.671479855617, 10952.616658417077, 7729.729459468892, 8211.05572859117, 8344.374171937128, 8361.790955226168, 8377.413581049268, 5825.621334613236, 5971.134185543159, 6000.639961602318, 6061.818145455643, 6065.181200537871, 8355.83288334242, 9630.181434578139, 9888.950555247178, 10013.148882345082, 10046.995300469895, 9324.300677449353, 9800.31397802147, 10162.560985363607, 10218.540165692413, 10365.63720269787, 7005.544639598614, 7248.0, 7320.890186647098, 7614.466987310835, 7623.428242881695, 8211.21993410625, 8230.0, 8242.711505097293, 8317.584120793816, 8353.749387518385, 6245.469135123565, 6383.808485745435, 7314.122305323395, 7427.25727427253, 7464.141623713343, 7202.96398518004, 7320.084989376328, 7320.560766354036, 7330.083739532559, 7379.8155046124275, 6888.862222755617, 7035.401990830836, 7037.859242815218, 7039.436845052281, 7040.5071644984355, 4808.9519104808505, 4931.4328852181025, 4973.975130124326, 5010.123228435083, 5133.409657889241, 3802.4866643003948, 4977.900441991213, 5019.598432125348, 5165.276861239505, 5200.089984252816], "value": 4495.577302193148, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.2219928392622, 295.9082684367846, 305.68361745593313, 309.24081380211567, 314.3571396494169, 3851.595582463843, 4824.276358546215, 5230.2416149658275, 5261.631685781997, 5286.471352864712, 4887.511248875119, 5205.973970130153, 5226.24219488174, 5242.606804489669, 5255.318298144595, 3807.4669546263544, 3902.8434717026275, 4004.8598299059395, 4017.3973903914352, 4034.596540345964, 3326.9500957485657, 3641.6722494975447, 3761.0, 4036.3249251560715, 4041.6968727345566, 3520.894373168809, 3933.8033098345113, 3982.4424580558743, 3995.720299579031, 4046.898827529306, 3734.421164719473, 3754.7371683982133, 4007.639312461878, 4020.2160578687167, 4023.476947996771, 3105.9750282406976, 3312.3044160726276, 3485.35520928629, 3496.4930085137767, 3525.5769307682986, 4032.056598682978, 4808.230683090724, 5110.463401342842, 5155.66488178271, 5208.192730126836, 4501.572350626685, 4505.053938672882, 4506.346579745951, 4516.36770852081, 4531.782434471496, 4887.9246565755475, 5104.2088476286235, 5248.501392367719, 5541.889162216733, 5561.63849349791, 3625.3111908737255, 3727.4781530585738, 3763.8118094095184, 3767.0, 3782.8865134046023, 5466.644668096601, 5631.114530973615, 5735.254416925773, 5768.913466298009, 5772.797952071699, 3174.6349169845303, 3392.338493993697, 3807.1053302473897, 3821.312163810541, 3831.9616803832164, 3114.4549703801745, 3641.5630124384716, 3647.8905632831056, 3872.0, 3884.378499440076, 4150.833966641361, 4491.3487544306545, 4494.10117976406, 4500.054988452396, 4572.268437050056, 4442.045499979999, 4716.0, 5527.44725527444, 5537.501624853782, 5554.166874968787, 1122.7698321844166, 1168.5384273212035, 1217.1723228204905, 1277.7861032019619, 1282.9422675979604, 856.2850020233175, 860.5654144656908, 877.6138499060393, 884.9955750221208, 885.5395194498825, 4600.907981840344, 5209.895802083937, 5296.841094767163, 5299.364076310791, 5303.36359636846, 5467.289252397163, 5472.425395333512, 5737.598368114275, 5740.0, 5745.368009518949, 5331.280277162611, 5335.039692855245, 5348.79652078287, 5352.0, 5399.514043736082, 2755.793315501305, 2922.4739546881765, 2965.2438628149685, 2991.820490770561, 3008.6389633244153, 4945.406551213878, 4958.975205123951, 5610.87782443513, 5950.375210602911, 6005.939940600625, 5739.426057394227, 5921.134842524591, 5938.524918006549, 5944.494717948936, 5953.880922381615, 5807.970960145172, 5971.19388882504, 5979.970100149559, 5989.9101513477335, 6042.425969532887, 5785.392533783892, 5786.739596718242, 5805.042168042226, 5805.506531944832, 5857.179994800646, 4973.900521989613, 5687.260656114679, 5702.37273899879, 5726.942730572641, 5744.10444553288, 5664.612170018372, 5949.881002380015, 5950.166976623363, 5964.343922168471, 5980.551458640706, 3242.578464799561, 3807.314683357022, 3825.6365645263654, 3848.615138486241, 3884.504465780751, 5774.797882074107, 5779.682117483579, 5790.449907258803, 5814.825555233349, 5829.912551311819, 5252.317198764136, 5578.916316255179, 5633.324001119728, 5642.6614403135945, 5796.478316951495, 4653.418322709661, 4875.658703890694, 5597.63615365, 5608.691521966168, 5638.774449021993, 4450.531324662788, 5216.321878155815, 5224.615476898549, 5433.646812957145, 5514.503694667499, 5582.692951887685, 5597.244372009889, 5598.916016259678, 5605.915911261416, 5640.746166422438, 5036.111458203174, 5234.7906083756225, 5486.396496385474, 5489.212443414031, 5496.477834605706], "value": 4052.3515228107885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.4610872682398, 713.5718568858682, 732.8387754693965, 766.6396793507054, 840.1976112812263, 11919.523219071238, 15207.695846083072, 15888.841111588881, 15942.282597283122, 15964.920175399122, 13354.33228338584, 13530.18818870866, 13559.52541661042, 13591.592252232424, 13652.590422287323, 683.4259222253306, 704.1339152842007, 851.7274472168908, 864.6109250837117, 866.6403442571357, 696.5228818259483, 839.1273076000982, 869.3740506835101, 881.6561540999015, 884.4251236696134, 914.7850255190058, 916.4043371808311, 918.1231923513062, 919.4850883505259, 924.2652091587204, 707.2856415020866, 912.4160537256162, 914.1224424552438, 914.7758799094198, 916.4821875640242, 550.018217481793, 657.8782925158813, 675.2201207605224, 677.5392732941598, 683.453237410075, 14810.555683329518, 14850.480233191787, 14875.628109297355, 14895.255237238203, 14898.255087245703, 13255.66860828487, 13563.660908477268, 13701.657458563515, 13982.650433739136, 14107.858921410758, 12052.578159764365, 12270.293446071977, 12270.32513211773, 12301.446434910362, 12884.16252943565, 4419.206397479279, 4459.420275364214, 4522.0, 4576.336431217488, 4678.1813182692895, 112847.0, 114453.0, 115006.84993150044, 115506.0, 117250.41374793071, 9620.615175392906, 10768.623098191525, 10839.512221950032, 10842.457877106113, 11181.32912025281, 21086.894565527382, 21090.0, 21111.472213194793, 21142.894285528473, 21144.471388215417, 7684.884726729104, 7773.766786996399, 7870.5270913398945, 7879.093904200976, 8014.71948481812, 4276.764777937181, 4386.780660967002, 4406.3170208617075, 4435.467743870691, 4553.362529245908, 3388.711959483422, 3518.208403109331, 3523.489094081344, 3524.136586536315, 3524.84138213781, 562.2100948167831, 648.7033372513469, 661.2593894837812, 667.0494545272967, 668.7091115364832, 555.2143716640908, 600.3366280260336, 603.2188316130533, 611.7583554495949, 641.8459569703331, 9022.909770902206, 9080.81838363229, 9172.174504294646, 9237.953810230905, 10733.624323148651, 584.231735268121, 589.2100902227947, 650.9804705858832, 657.5101549345673, 679.9116114905031, 4281.529031806561, 4311.525732169459, 4436.334549817553, 4459.2865141577195, 4506.0, 115889.4205528967, 124854.0, 133370.6657333555, 142316.28841855933, 148982.0, 82940.17059829445, 125913.7408625902, 127995.36002319929, 128423.0, 129160.0, 103726.0, 105497.47251263694, 106825.46587267169, 107693.46153269183, 108281.45859270653, 8890.244329232088, 8913.509756963429, 8937.910620893708, 8977.775555611162, 9097.1812536872, 58314.12528812156, 58732.0, 59572.70213648904, 59668.70165649144, 59953.10070349035, 79823.0, 87063.0, 91976.54011729898, 95429.0, 98018.0, 12887.871121288666, 12983.610491685264, 13189.472421103048, 13678.794818077937, 13715.177089374669, 14801.481948131765, 14840.40638374453, 15072.020318679251, 15094.245287735788, 15256.008359457044, 13794.310284485535, 14049.719005620034, 14250.144991300554, 14263.928680356947, 14455.349509271897, 11805.586804461802, 13961.232132232824, 14198.432062717375, 14274.571762847132, 14362.998100580087, 11789.41052947332, 13094.738105237651, 13409.69547782611, 13834.93082534581, 13928.094673846168, 8328.791780205544, 10828.837567436653, 11212.27120237182, 11450.427478626201, 11683.539557555305, 11323.32060076398, 11490.82763758561, 11698.824517632072, 11798.587049453228, 11990.580329688419], "value": 7741.880061973776, "unit": "score"}], "name": "dromaeo_css", "value": 7191.643606659522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 112.0, 120.0, 166.0, 156.0, 113.0, 113.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 122.0, 184.0, 145.0, 106.0, 142.0, 141.0, 180.0, 114.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 187.0, 202.0, 184.0, 183.0, 187.0, 183.0, 184.0, 178.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 89.0, 61.0, 58.0, 59.0, 57.0, 80.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 125.0, 102.0, 67.0, 68.0, 91.0, 67.0, 69.0, 89.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 87.0, 84.0, 86.0, 84.0, 85.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 103.0, 94.0, 94.0, 86.0, 129.0, 152.0, 103.0, 93.0, 92.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 80.0, 86.0, 85.0, 83.0, 85.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 54.0, 55.0, 56.0, 54.0, 54.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 69.0, 76.0, 72.0, 69.0, 70.0, 74.0, 71.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 88.0, 89.0, 86.0, 88.0, 88.0, 92.0, 101.0, 108.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 129.0, 159.0, 154.0, 159.0, 162.0, 157.0, 135.0, 170.0, 144.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 239.0, 185.0, 172.0, 172.0, 168.0, 169.0, 165.0, 163.0, 172.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0, 67.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1493.5, "alertThreshold": 2.0}]} 15:28:13 INFO - Return code: 0 15:28:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:28:13 INFO - # TBPL SUCCESS # 15:28:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:28:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:28:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:28:13 INFO - 2016-09-30 15:28:13.861 screenresolution[1668:9930] starting screenresolution argv=screenresolution get 15:28:13 INFO - 2016-09-30 15:28:13.864 screenresolution[1668:9930] Display 0: 1600x1200x32@60 15:28:13 INFO - 2016-09-30 15:28:13.870 screenresolution[1669:9933] starting screenresolution argv=screenresolution list 15:28:13 INFO - Available Modes on Display 0 15:28:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:28:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:28:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:28:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:28:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:28:14 INFO - Intel Iris: 15:28:14 INFO - Chipset Model: Intel Iris 15:28:14 INFO - Type: GPU 15:28:14 INFO - Bus: Built-In 15:28:14 INFO - VRAM (Dynamic, Max): 1536 MB 15:28:14 INFO - Vendor: Intel (0x8086) 15:28:14 INFO - Device ID: 0x0a2e 15:28:14 INFO - Revision ID: 0x0009 15:28:14 INFO - Displays: 15:28:14 INFO - Display: 15:28:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:28:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:28:14 INFO - Display Serial Number: 1600x1200 60 15:28:14 INFO - Main Display: Yes 15:28:14 INFO - Mirror: Off 15:28:14 INFO - Online: Yes 15:28:14 INFO - Rotation: Supported 15:28:14 INFO - Return code: 0 15:28:14 INFO - Running post-action listener: _resource_record_post_action 15:28:14 INFO - [mozharness: 2016-09-30 22:28:14.113026Z] Finished run-tests step (success) 15:28:14 INFO - Running post-run listener: _resource_record_post_run 15:28:14 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38413312; Write bytes: 707189760; Read time: 379; Write time: 2156 15:28:14 INFO - TinderboxPrint: CPU usage
53.4% 15:28:14 INFO - TinderboxPrint: I/O read bytes / time
38,413,312 / 379 15:28:14 INFO - TinderboxPrint: I/O write bytes / time
707,189,760 / 2,156 15:28:14 INFO - TinderboxPrint: CPU idle
2,260.6 (46.6%) 15:28:14 INFO - TinderboxPrint: CPU system
972.9 (20.0%) 15:28:14 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 15:28:14 INFO - TinderboxPrint: Swap in / out
445,358,080 / 0 15:28:14 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396753408; Write bytes: 385877504; Read time: 29562; Write time: 738 15:28:14 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 32185344; Write bytes: 295596032; Read time: 296; Write time: 1342 15:28:14 INFO - Running post-run listener: _upload_blobber_files 15:28:14 INFO - Blob upload gear active. 15:28:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:28:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:28:14 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:28:14 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:28:15 INFO - (blobuploader) - INFO - Open directory for files ... 15:28:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:28:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:28:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:15 INFO - (blobuploader) - INFO - Done attempting. 15:28:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:28:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:28:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:16 INFO - (blobuploader) - INFO - Done attempting. 15:28:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:28:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:28:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:18 INFO - (blobuploader) - INFO - Done attempting. 15:28:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:28:18 INFO - Return code: 0 15:28:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:28:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:28:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7551c9cce4271f0601d62ea079d110d809c4d0088cca37e379727e6f6d7b2a48cbc9c6d0d5ed6c762cb059d9947c37d28bd48308467e82c09d68c8f5954775e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ebe3e343932bb10e2c36d4406a1c269875c137423ac6d119138ff6275305d74b1e78f8ddd2c4d9a1cf2b877b4b8f00ade974ce4a207c2b8319ddb874886f3f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b35f2ac394842deb9362d089f9d8634f7cfe872c5311934f7454262dfee4b958d629f7ea20ab906b38c5a0a7b91ff35b0cdcf60650e99996ebd52ba98b033bc"} 15:28:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:28:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:28:18 INFO - Contents: 15:28:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7551c9cce4271f0601d62ea079d110d809c4d0088cca37e379727e6f6d7b2a48cbc9c6d0d5ed6c762cb059d9947c37d28bd48308467e82c09d68c8f5954775e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ebe3e343932bb10e2c36d4406a1c269875c137423ac6d119138ff6275305d74b1e78f8ddd2c4d9a1cf2b877b4b8f00ade974ce4a207c2b8319ddb874886f3f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b35f2ac394842deb9362d089f9d8634f7cfe872c5311934f7454262dfee4b958d629f7ea20ab906b38c5a0a7b91ff35b0cdcf60650e99996ebd52ba98b033bc"} 15:28:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:28:18 INFO - Copying logs to upload dir... 15:28:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:28:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.889573 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-30 15:28:18.993941) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 15:28:18.997263) ========= 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.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7551c9cce4271f0601d62ea079d110d809c4d0088cca37e379727e6f6d7b2a48cbc9c6d0d5ed6c762cb059d9947c37d28bd48308467e82c09d68c8f5954775e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ebe3e343932bb10e2c36d4406a1c269875c137423ac6d119138ff6275305d74b1e78f8ddd2c4d9a1cf2b877b4b8f00ade974ce4a207c2b8319ddb874886f3f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b35f2ac394842deb9362d089f9d8634f7cfe872c5311934f7454262dfee4b958d629f7ea20ab906b38c5a0a7b91ff35b0cdcf60650e99996ebd52ba98b033bc"} build_url:https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011050 build_url: 'https://queue.taskcluster.net/v1/task/Y3dOt9rIRTe1x3z0RUMcxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7551c9cce4271f0601d62ea079d110d809c4d0088cca37e379727e6f6d7b2a48cbc9c6d0d5ed6c762cb059d9947c37d28bd48308467e82c09d68c8f5954775e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ebe3e343932bb10e2c36d4406a1c269875c137423ac6d119138ff6275305d74b1e78f8ddd2c4d9a1cf2b877b4b8f00ade974ce4a207c2b8319ddb874886f3f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b35f2ac394842deb9362d089f9d8634f7cfe872c5311934f7454262dfee4b958d629f7ea20ab906b38c5a0a7b91ff35b0cdcf60650e99996ebd52ba98b033bc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 15:28:19.029666) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:28:19.030004) ========= 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.PjFQYLpe3A/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.QQXkmoe37V/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 15:28:19.078555) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 15:28:19.078876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 15:28:19.079198) ========= ========= Total master_lag: 0.27 =========