builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0254 starttime: 1476223539.74 results: success (0) buildid: 20161011121046 builduid: a24d6d5684e04daa944da3b55c28972d revision: fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.743186) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.743618) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.743910) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.768416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.768979) ========= 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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-10-11 15:05:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 59.3M=0s 2016-10-11 15:05:39 (59.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082320 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.874059) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.874705) ========= 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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.027332 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:39.923758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 15:05:39.924137) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-10-11 15:05:40,004 truncating revision to first 12 chars 2016-10-11 15:05:40,004 Setting DEBUG logging. 2016-10-11 15:05:40,005 attempt 1/10 2016-10-11 15:05:40,005 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fe1d7c28668f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 15:05:40,692 unpacking tar archive at: fx-team-fe1d7c28668f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.360162 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 15:05:41.306576) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:41.306999) ========= script_repo_revision: fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:41.307412) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:41.307691) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 15:05:41.326629) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-11 15:05:41.327012) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:05:41 INFO - MultiFileLogger online at 20161011 15:05:41 in /builds/slave/test 15:05:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:05:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:05:41 INFO - {'append_to_log': False, 15:05:41 INFO - 'base_work_dir': '/builds/slave/test', 15:05:41 INFO - 'blob_upload_branch': 'Fx-Team', 15:05:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:05:41 INFO - 'branch': 'Fx-Team', 15:05:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:05:41 INFO - 'config_files': ('talos/mac_config.py',), 15:05:41 INFO - 'default_actions': ('clobber', 15:05:41 INFO - 'read-buildbot-config', 15:05:41 INFO - 'download-and-extract', 15:05:41 INFO - 'populate-webroot', 15:05:41 INFO - 'create-virtualenv', 15:05:41 INFO - 'install', 15:05:41 INFO - 'run-tests'), 15:05:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:05:41 INFO - 'download_minidump_stackwalk': True, 15:05:41 INFO - 'download_symbols': 'ondemand', 15:05:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:05:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:05:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:05:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:05:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:05:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:05:41 INFO - 'installer_path': 'installer.exe', 15:05:41 INFO - 'log_level': 'info', 15:05:41 INFO - 'log_name': 'talos', 15:05:41 INFO - 'log_to_console': True, 15:05:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:05:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:05:41 INFO - 'opt_config_files': (), 15:05:41 INFO - 'pip_index': False, 15:05:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:05:41 INFO - 'cmd': ('bash', 15:05:41 INFO - '-c', 15:05:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:05:41 INFO - 'enabled': True, 15:05:41 INFO - 'halt_on_failure': False, 15:05:41 INFO - 'name': 'check_screen_resolution'},), 15:05:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:05:41 INFO - 'cmd': ('bash', 15:05:41 INFO - '-c', 15:05:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:05:41 INFO - 'enabled': True, 15:05:41 INFO - 'halt_on_failure': False, 15:05:41 INFO - 'name': 'check_screen_resolution'},), 15:05:41 INFO - 'run_cmd_checks_enabled': True, 15:05:41 INFO - 'sps_profile': False, 15:05:41 INFO - 'sps_profile_interval': 0, 15:05:41 INFO - 'suite': 'dromaeojs-e10s', 15:05:41 INFO - 'system_bits': '32', 15:05:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:05:41 INFO - 'title': 't-yosemite-r7-0254', 15:05:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:05:41 INFO - 'use_talos_json': True, 15:05:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:05:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:05:41 INFO - 'work_dir': 'build'} 15:05:41 INFO - [mozharness: 2016-10-11 22:05:41.550014Z] Running clobber step. 15:05:41 INFO - Running pre-action listener: _resource_record_pre_action 15:05:41 INFO - Running main action method: clobber 15:05:41 INFO - rmtree: /builds/slave/test/build 15:05:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:05:43 INFO - Running post-action listener: _resource_record_post_action 15:05:43 INFO - [mozharness: 2016-10-11 22:05:43.676927Z] Finished clobber step (success) 15:05:43 INFO - [mozharness: 2016-10-11 22:05:43.677077Z] Running read-buildbot-config step. 15:05:43 INFO - Running pre-action listener: _resource_record_pre_action 15:05:43 INFO - Running main action method: read_buildbot_config 15:05:43 INFO - Using buildbot properties: 15:05:43 INFO - { 15:05:43 INFO - "project": "", 15:05:43 INFO - "product": "firefox", 15:05:43 INFO - "script_repo_revision": "production", 15:05:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 15:05:43 INFO - "repository": "", 15:05:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 15:05:43 INFO - "buildid": "20161011121046", 15:05:43 INFO - "pgo_build": "False", 15:05:43 INFO - "basedir": "/builds/slave/test", 15:05:43 INFO - "buildnumber": 11, 15:05:43 INFO - "slavename": "t-yosemite-r7-0254", 15:05:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:05:43 INFO - "platform": "yosemite_r7", 15:05:43 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 15:05:43 INFO - "branch": "fx-team", 15:05:43 INFO - "repo_path": "integration/fx-team", 15:05:43 INFO - "revision": "fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387", 15:05:43 INFO - "stage_platform": "macosx64", 15:05:43 INFO - "builduid": "a24d6d5684e04daa944da3b55c28972d", 15:05:43 INFO - "slavebuilddir": "test" 15:05:43 INFO - } 15:05:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:05:43 INFO - Running post-action listener: _resource_record_post_action 15:05:43 INFO - [mozharness: 2016-10-11 22:05:43.680390Z] Finished read-buildbot-config step (success) 15:05:43 INFO - [mozharness: 2016-10-11 22:05:43.680512Z] Running download-and-extract step. 15:05:43 INFO - Running pre-action listener: _resource_record_pre_action 15:05:43 INFO - Running main action method: download_and_extract 15:05:43 INFO - mkdir: /builds/slave/test/build/tests 15:05: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:05:43 INFO - trying https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:05:43 INFO - Downloading https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/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:05:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/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:05:44 INFO - Downloaded 1199 bytes. 15:05:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:05:44 INFO - Using the following test package requirements: 15:05:44 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:05:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:05:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:05:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'jsshell-mac.zip'], 15:05:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:05:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:05:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:05:44 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:05:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:05:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:05:44 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:05:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:05:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:05:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:05:44 INFO - Fetch https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:05:45 INFO - Http code: 200 15:05:45 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 15:05:45 INFO - x-amz-cf-id: LvQvodlkKrR0syKWTNOy507Yz2nxPtkxljrrmYhbDHyinIVl4zdDqg== 15:05:45 INFO - x-amz-version-id: 1TdWUQN4OHFiYDFUhbq7VZthMjv28fMH 15:05:45 INFO - Expected file size: 16508479 15:05:45 INFO - Obtained file size: 16508479 15:05:52 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:05:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:05:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:05:52 INFO - Fetch https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:05:53 INFO - Http code: 200 15:05:53 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 15:05:53 INFO - x-amz-cf-id: 2up4lEEwozw0qD4pBfq2y9lhrSbMxPTm2pGm2BXijau-ZMGiGBb1oA== 15:05:53 INFO - x-amz-version-id: 1TdWUQN4OHFiYDFUhbq7VZthMjv28fMH 15:05:53 INFO - Expected file size: 16508479 15:05:53 INFO - Obtained file size: 16508479 15:05:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:05:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:05:59 INFO - Fetch https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:06:00 INFO - Http code: 200 15:06:00 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 15:06:00 INFO - x-amz-cf-id: mE5G2ypXD5MWES4jR-EmM0vKrPap_H_DcihcL8tMxpBzDx5YdXDwOA== 15:06:00 INFO - x-amz-version-id: flbRT7LOV.v_105HXx9ebXZvukeXdhvr 15:06:00 INFO - Expected file size: 14409006 15:06:00 INFO - Obtained file size: 14409006 15:06:01 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:06:01 INFO - trying https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:06:01 INFO - Downloading https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:06:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/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:06:03 INFO - Downloaded 111677843 bytes. 15:06:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:06:03 INFO - mkdir: /builds/slave/test/properties 15:06:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:06:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:06:03 INFO - Contents: 15:06:03 INFO - build_url:https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:06:04 INFO - Running post-action listener: _resource_record_post_action 15:06:04 INFO - Running post-action listener: set_extra_try_arguments 15:06:04 INFO - [mozharness: 2016-10-11 22:06:04.608756Z] Finished download-and-extract step (success) 15:06:04 INFO - [mozharness: 2016-10-11 22:06:04.608874Z] Running populate-webroot step. 15:06:04 INFO - Running pre-action listener: _resource_record_pre_action 15:06:04 INFO - Running main action method: populate_webroot 15:06:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:06:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:06:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:06:04 INFO - '--tppagecycles', 15:06:04 INFO - '7'], 15:06:04 INFO - 'tests': ['tsvgm']}}, 15:06:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:06:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:06:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:06:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:06:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['damp', 'tps']}, 15:06:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'tests': ['damp', 'tps']}, 15:06:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['dromaeo_dom']}, 15:06:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:06:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['basic_compositor_video']}, 15:06:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:06:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['a11yr', 15:06:04 INFO - 'ts_paint', 15:06:04 INFO - 'tpaint', 15:06:04 INFO - 'sessionrestore', 15:06:04 INFO - 'sessionrestore_no_auto_restore', 15:06:04 INFO - 'tabpaint']}, 15:06:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['tsvgx', 15:06:04 INFO - 'tsvgr_opacity', 15:06:04 INFO - 'tart', 15:06:04 INFO - 'tscrollx', 15:06:04 INFO - 'cart']}, 15:06:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:06:04 INFO - 'tsvgr_opacity', 15:06:04 INFO - 'tart', 15:06:04 INFO - 'tscrollx', 15:06:04 INFO - 'cart']}, 15:06:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'talos_options': ['--disable-e10s'], 15:06:04 INFO - 'tests': ['tp5o']}, 15:06:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'tests': ['tp5o']}, 15:06:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'talos_options': ['--disable-e10s', 15:06:04 INFO - '--xperf_path', 15:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:04 INFO - 'tests': ['tp5n']}, 15:06:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:04 INFO - 'talos_options': ['--xperf_path', 15:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:04 INFO - 'tests': ['tp5n']}}, 15:06:04 INFO - 'talos.zip': {'path': '', 15:06:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:06:04 INFO - Running post-action listener: _resource_record_post_action 15:06:04 INFO - [mozharness: 2016-10-11 22:06:04.627451Z] Finished populate-webroot step (success) 15:06:04 INFO - [mozharness: 2016-10-11 22:06:04.627561Z] Running create-virtualenv step. 15:06:04 INFO - Running pre-action listener: _resource_record_pre_action 15:06:04 INFO - Running main action method: create_virtualenv 15:06:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:06:04 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:06:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:06:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:06:04 INFO - Using real prefix '/tools/python27' 15:06:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:06:05 INFO - Installing distribute.............................................................................................................................................................................................done. 15:06:08 INFO - Installing pip.................done. 15:06:08 INFO - Return code: 0 15:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:06:08 INFO - Reading from file tmpfile_stdout 15:06:08 INFO - Output received: 15:06:08 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:06:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:06:08 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:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:08 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:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:08 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:08 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:06:08 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:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:08 INFO - 'HOME': '/Users/cltbld', 15:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:08 INFO - 'LOGNAME': 'cltbld', 15:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:08 INFO - 'MOZ_NO_REMOTE': '1', 15:06:08 INFO - 'NO_EM_RESTART': '1', 15:06:08 INFO - 'PAGER': '/bin/cat', 15:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:08 INFO - 'PWD': '/builds/slave/test', 15:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:08 INFO - 'SHELL': '/bin/bash', 15:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:08 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:08 INFO - 'USER': 'cltbld', 15:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:08 INFO - 'XPC_FLAGS': '0x0', 15:06:08 INFO - 'XPC_SERVICE_NAME': '0', 15:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:08 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:06:08 INFO - Cleaning up... 15:06:08 INFO - Return code: 0 15:06:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:06:08 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:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:08 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:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:08 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:08 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:06:08 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:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:08 INFO - 'HOME': '/Users/cltbld', 15:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:08 INFO - 'LOGNAME': 'cltbld', 15:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:08 INFO - 'MOZ_NO_REMOTE': '1', 15:06:08 INFO - 'NO_EM_RESTART': '1', 15:06:08 INFO - 'PAGER': '/bin/cat', 15:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:08 INFO - 'PWD': '/builds/slave/test', 15:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:08 INFO - 'SHELL': '/bin/bash', 15:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:08 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:08 INFO - 'USER': 'cltbld', 15:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:08 INFO - 'XPC_FLAGS': '0x0', 15:06:08 INFO - 'XPC_SERVICE_NAME': '0', 15:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:09 INFO - Downloading/unpacking psutil>=3.1.1 15:06:09 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:06:09 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:06:09 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:06:09 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:06:09 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:06:09 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:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:06:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:12 INFO - Installing collected packages: psutil 15:06:12 INFO - Running setup.py install for psutil 15:06:12 INFO - building 'psutil._psutil_osx' extension 15:06:12 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:06:13 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:06:13 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:06:13 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:06:13 INFO - building 'psutil._psutil_posix' extension 15:06:13 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:06:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:06:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:06:13 INFO - ^ 15:06:13 INFO - 1 warning generated. 15:06:13 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:06:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:13 INFO - Successfully installed psutil 15:06:13 INFO - Cleaning up... 15:06:13 INFO - Return code: 0 15:06:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:06:13 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:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:13 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:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:13 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:13 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:06:13 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:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:13 INFO - 'HOME': '/Users/cltbld', 15:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:13 INFO - 'LOGNAME': 'cltbld', 15:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:13 INFO - 'MOZ_NO_REMOTE': '1', 15:06:13 INFO - 'NO_EM_RESTART': '1', 15:06:13 INFO - 'PAGER': '/bin/cat', 15:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:13 INFO - 'PWD': '/builds/slave/test', 15:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:13 INFO - 'SHELL': '/bin/bash', 15:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:13 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:13 INFO - 'USER': 'cltbld', 15:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:13 INFO - 'XPC_FLAGS': '0x0', 15:06:13 INFO - 'XPC_SERVICE_NAME': '0', 15:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:06:13 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:06:13 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:06:13 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:06:13 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:06:13 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:06:13 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:06:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:06:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:06:16 INFO - Installing collected packages: mozsystemmonitor 15:06:16 INFO - Running setup.py install for mozsystemmonitor 15:06:16 INFO - Successfully installed mozsystemmonitor 15:06:16 INFO - Cleaning up... 15:06:16 INFO - Return code: 0 15:06:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:06:16 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:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:16 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:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:16 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:16 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:06:16 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:06:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:16 INFO - 'HOME': '/Users/cltbld', 15:06:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:16 INFO - 'LOGNAME': 'cltbld', 15:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:16 INFO - 'MOZ_NO_REMOTE': '1', 15:06:16 INFO - 'NO_EM_RESTART': '1', 15:06:16 INFO - 'PAGER': '/bin/cat', 15:06:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:16 INFO - 'PWD': '/builds/slave/test', 15:06:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:16 INFO - 'SHELL': '/bin/bash', 15:06:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:16 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:16 INFO - 'USER': 'cltbld', 15:06:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:16 INFO - 'XPC_FLAGS': '0x0', 15:06:16 INFO - 'XPC_SERVICE_NAME': '0', 15:06:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:16 INFO - Downloading/unpacking jsonschema==2.5.1 15:06:16 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:06:16 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:06:16 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:06:16 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:06:16 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:06:16 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:06:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:06:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:19 INFO - Downloading functools32-3.2.3-2.tar.gz 15:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:06:20 INFO - warning: no files found matching '*.txt' 15:06:20 INFO - no previously-included directories found matching 'build' 15:06:20 INFO - no previously-included directories found matching 'dist' 15:06:20 INFO - no previously-included directories found matching '.git*' 15:06:20 INFO - Installing collected packages: jsonschema, functools32 15:06:20 INFO - Running setup.py install for functools32 15:06:20 INFO - warning: no files found matching '*.txt' 15:06:20 INFO - no previously-included directories found matching 'build' 15:06:20 INFO - no previously-included directories found matching 'dist' 15:06:20 INFO - no previously-included directories found matching '.git*' 15:06:20 INFO - Successfully installed jsonschema functools32 15:06:20 INFO - Cleaning up... 15:06:20 INFO - Return code: 0 15:06:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:06:20 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:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:20 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:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:20 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:20 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:06:20 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:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:20 INFO - 'HOME': '/Users/cltbld', 15:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:20 INFO - 'LOGNAME': 'cltbld', 15:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:20 INFO - 'MOZ_NO_REMOTE': '1', 15:06:20 INFO - 'NO_EM_RESTART': '1', 15:06:20 INFO - 'PAGER': '/bin/cat', 15:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:20 INFO - 'PWD': '/builds/slave/test', 15:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:20 INFO - 'SHELL': '/bin/bash', 15:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:20 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:20 INFO - 'USER': 'cltbld', 15:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:20 INFO - 'XPC_FLAGS': '0x0', 15:06:20 INFO - 'XPC_SERVICE_NAME': '0', 15:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:06:20 INFO - Cleaning up... 15:06:20 INFO - Return code: 0 15:06:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:06:20 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:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:20 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:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:20 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:20 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:06:20 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:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:20 INFO - 'HOME': '/Users/cltbld', 15:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:20 INFO - 'LOGNAME': 'cltbld', 15:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:20 INFO - 'MOZ_NO_REMOTE': '1', 15:06:20 INFO - 'NO_EM_RESTART': '1', 15:06:20 INFO - 'PAGER': '/bin/cat', 15:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:20 INFO - 'PWD': '/builds/slave/test', 15:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:20 INFO - 'SHELL': '/bin/bash', 15:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:20 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:20 INFO - 'USER': 'cltbld', 15:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:20 INFO - 'XPC_FLAGS': '0x0', 15:06:20 INFO - 'XPC_SERVICE_NAME': '0', 15:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:20 INFO - Downloading/unpacking blobuploader==1.2.4 15:06:20 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:06:20 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:06:20 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:06:20 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:06:20 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:06:20 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:06:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:06:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:06:23 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:06:23 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:06:23 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:06:23 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:06:23 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:06:23 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:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:06:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:24 INFO - Downloading docopt-0.6.1.tar.gz 15:06:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:06:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:06:24 INFO - Running setup.py install for blobuploader 15:06:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:06:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:06:24 INFO - Running setup.py install for requests 15:06:25 INFO - Running setup.py install for docopt 15:06:25 INFO - Successfully installed blobuploader requests docopt 15:06:25 INFO - Cleaning up... 15:06:25 INFO - Return code: 0 15:06:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:25 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:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:25 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:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:25 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:25 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:06:25 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:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:25 INFO - 'HOME': '/Users/cltbld', 15:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:25 INFO - 'LOGNAME': 'cltbld', 15:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:25 INFO - 'MOZ_NO_REMOTE': '1', 15:06:25 INFO - 'NO_EM_RESTART': '1', 15:06:25 INFO - 'PAGER': '/bin/cat', 15:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:25 INFO - 'PWD': '/builds/slave/test', 15:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:25 INFO - 'SHELL': '/bin/bash', 15:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:25 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:25 INFO - 'USER': 'cltbld', 15:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:25 INFO - 'XPC_FLAGS': '0x0', 15:06:25 INFO - 'XPC_SERVICE_NAME': '0', 15:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:25 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-cGkDF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-gnBQaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9LqaJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-iYDW0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-GmbCzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-EIG_67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-XKDvCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-yTyxKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Bswgh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:26 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-VuPKLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-JxbZNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-1CJyYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-6eANq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Ejr9KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-pt61nP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-_G8421-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:27 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-sI8f58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:06:27 INFO - Running setup.py install for manifestparser 15:06:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:06:27 INFO - Running setup.py install for mozcrash 15:06:28 INFO - Running setup.py install for mozdebug 15:06:28 INFO - Running setup.py install for mozdevice 15:06:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Running setup.py install for mozfile 15:06:28 INFO - Running setup.py install for mozhttpd 15:06:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Running setup.py install for mozinfo 15:06:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Running setup.py install for mozInstall 15:06:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:06:28 INFO - Running setup.py install for mozleak 15:06:29 INFO - Running setup.py install for mozlog 15:06:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Running setup.py install for moznetwork 15:06:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Running setup.py install for mozprocess 15:06:29 INFO - Running setup.py install for mozprofile 15:06:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Running setup.py install for mozrunner 15:06:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:06:29 INFO - Running setup.py install for mozscreenshot 15:06:30 INFO - Running setup.py install for moztest 15:06:30 INFO - Running setup.py install for mozversion 15:06:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:06:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:06:30 INFO - Cleaning up... 15:06:30 INFO - Return code: 0 15:06:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:30 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:30 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:06:30 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:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:30 INFO - 'HOME': '/Users/cltbld', 15:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:30 INFO - 'LOGNAME': 'cltbld', 15:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:30 INFO - 'MOZ_NO_REMOTE': '1', 15:06:30 INFO - 'NO_EM_RESTART': '1', 15:06:30 INFO - 'PAGER': '/bin/cat', 15:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:30 INFO - 'PWD': '/builds/slave/test', 15:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:30 INFO - 'SHELL': '/bin/bash', 15:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:30 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:30 INFO - 'USER': 'cltbld', 15:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:30 INFO - 'XPC_FLAGS': '0x0', 15:06:30 INFO - 'XPC_SERVICE_NAME': '0', 15:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:30 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-K5kKeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:30 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-g0Q9a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:30 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9yWSSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-6T4T7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-J4ZjNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Iq7PxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-h5fytS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0JOQmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-108Wby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-50NC6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-2EI6x6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-6CqatE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:31 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9wTKoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:32 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:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:32 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ibw_Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:32 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:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:32 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0grTp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:32 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:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:32 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-AInNAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:32 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:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:32 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-4_tzpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:32 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:06:32 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:06:32 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:06:32 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:06:32 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:06:32 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:06:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:06: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:06: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:06: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:06: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:06: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:06: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:06:34 INFO - Downloading blessings-1.6.tar.gz 15:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:06:35 INFO - Installing collected packages: blessings 15:06:35 INFO - Running setup.py install for blessings 15:06:35 INFO - Successfully installed blessings 15:06:35 INFO - Cleaning up... 15:06:35 INFO - Return code: 0 15:06:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:35 INFO - Reading from file tmpfile_stdout 15:06:35 INFO - Current package versions: 15:06:35 INFO - blessings == 1.6 15:06:35 INFO - blobuploader == 1.2.4 15:06:35 INFO - docopt == 0.6.1 15:06:35 INFO - functools32 == 3.2.3-2 15:06:35 INFO - jsonschema == 2.5.1 15:06:35 INFO - manifestparser == 1.1 15:06:35 INFO - mozInstall == 1.12 15:06:35 INFO - mozcrash == 0.17 15:06:35 INFO - mozdebug == 0.1 15:06:35 INFO - mozdevice == 0.48 15:06:35 INFO - mozfile == 1.2 15:06:35 INFO - mozhttpd == 0.7 15:06:35 INFO - mozinfo == 0.9 15:06:35 INFO - mozleak == 0.1 15:06:35 INFO - mozlog == 3.4 15:06:35 INFO - moznetwork == 0.27 15:06:35 INFO - mozprocess == 0.23 15:06:35 INFO - mozprofile == 0.28 15:06:35 INFO - mozrunner == 6.12 15:06:35 INFO - mozscreenshot == 0.1 15:06:35 INFO - mozsystemmonitor == 0.3 15:06:35 INFO - moztest == 0.7 15:06:35 INFO - mozversion == 1.4 15:06:35 INFO - psutil == 3.1.1 15:06:35 INFO - requests == 1.2.3 15:06:35 INFO - wsgiref == 0.1.2 15:06:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06: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:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06: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:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:36 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 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:36 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:06:36 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:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:36 INFO - 'HOME': '/Users/cltbld', 15:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:36 INFO - 'LOGNAME': 'cltbld', 15:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:36 INFO - 'MOZ_NO_REMOTE': '1', 15:06:36 INFO - 'NO_EM_RESTART': '1', 15:06:36 INFO - 'PAGER': '/bin/cat', 15:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:36 INFO - 'PWD': '/builds/slave/test', 15:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:36 INFO - 'SHELL': '/bin/bash', 15:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:36 INFO - 'USER': 'cltbld', 15:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:36 INFO - 'XPC_FLAGS': '0x0', 15:06:36 INFO - 'XPC_SERVICE_NAME': '0', 15:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 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:06:36 INFO - Cleaning up... 15:06:36 INFO - Return code: 0 15:06:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab83490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe80bd9efc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, '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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:36 INFO - 'HOME': '/Users/cltbld', 15:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:36 INFO - 'LOGNAME': 'cltbld', 15:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:36 INFO - 'MOZ_NO_REMOTE': '1', 15:06:36 INFO - 'NO_EM_RESTART': '1', 15:06:36 INFO - 'PAGER': '/bin/cat', 15:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:36 INFO - 'PWD': '/builds/slave/test', 15:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:36 INFO - 'SHELL': '/bin/bash', 15:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:06:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:36 INFO - 'USER': 'cltbld', 15:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:36 INFO - 'XPC_FLAGS': '0x0', 15:06:36 INFO - 'XPC_SERVICE_NAME': '0', 15:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:06:37 INFO - Cleaning up... 15:06:37 INFO - Return code: 0 15:06:37 INFO - Running post-action listener: _resource_record_post_action 15:06:37 INFO - Running post-action listener: _start_resource_monitoring 15:06:37 INFO - Starting resource monitoring. 15:06:37 INFO - [mozharness: 2016-10-11 22:06:37.224501Z] Finished create-virtualenv step (success) 15:06:37 INFO - [mozharness: 2016-10-11 22:06:37.225306Z] Running install step. 15:06:37 INFO - Running pre-action listener: _resource_record_pre_action 15:06:37 INFO - Running main action method: install 15:06:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:37 INFO - Reading from file tmpfile_stdout 15:06:37 INFO - Detecting whether we're running mozinstall >=1.0... 15:06:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:06:37 INFO - Reading from file tmpfile_stdout 15:06:37 INFO - Output received: 15:06:37 INFO - Usage: mozinstall [options] installer 15:06:37 INFO - Options: 15:06:37 INFO - -h, --help show this help message and exit 15:06:37 INFO - -d DEST, --destination=DEST 15:06:37 INFO - Directory to install application into. [default: 15:06:37 INFO - "/builds/slave/test"] 15:06:37 INFO - --app=APP Application being installed. [default: firefox] 15:06:37 INFO - mkdir: /builds/slave/test/build/application 15:06:37 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:06:37 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:07:12 INFO - Reading from file tmpfile_stdout 15:07:12 INFO - Output received: 15:07:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:07:12 INFO - Running post-action listener: _resource_record_post_action 15:07:12 INFO - [mozharness: 2016-10-11 22:07:12.673220Z] Finished install step (success) 15:07:12 INFO - [mozharness: 2016-10-11 22:07:12.673364Z] Running run-tests step. 15:07:12 INFO - Running pre-action listener: _resource_record_pre_action 15:07:12 INFO - Running main action method: run_tests 15:07:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:07:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:07:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:07:12 INFO - 2016-10-11 15:07:12.688 screenresolution[1633:7396] starting screenresolution argv=screenresolution get 15:07:12 INFO - 2016-10-11 15:07:12.690 screenresolution[1633:7396] Display 0: 1600x1200x32@60 15:07:12 INFO - 2016-10-11 15:07:12.697 screenresolution[1634:7398] starting screenresolution argv=screenresolution list 15:07:12 INFO - Available Modes on Display 0 15:07:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:07:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:07:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:07:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:07:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:07:12 INFO - Intel Iris: 15:07:12 INFO - Chipset Model: Intel Iris 15:07:12 INFO - Type: GPU 15:07:12 INFO - Bus: Built-In 15:07:12 INFO - VRAM (Dynamic, Max): 1536 MB 15:07:12 INFO - Vendor: Intel (0x8086) 15:07:12 INFO - Device ID: 0x0a2e 15:07:12 INFO - Revision ID: 0x0009 15:07:12 INFO - Displays: 15:07:12 INFO - Display: 15:07:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:07:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:07:12 INFO - Display Serial Number: 1600x1200 60 15:07:12 INFO - Main Display: Yes 15:07:12 INFO - Mirror: Off 15:07:12 INFO - Online: Yes 15:07:12 INFO - Rotation: Supported 15:07:12 INFO - Return code: 0 15:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:07:12 INFO - Python 2.7.3 15:07:12 INFO - Return code: 0 15:07:12 INFO - grabbing minidump binary from tooltool 15:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:12 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 0x10a997d30>, '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 0x10a7bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a98fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:07:12 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:07:12 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:07:12 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:07:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:07:13 INFO - Return code: 0 15:07:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:07:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:07:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2ZJtl4e6b/Render', 15:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:13 INFO - 'HOME': '/Users/cltbld', 15:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:13 INFO - 'LOGNAME': 'cltbld', 15:07:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:07:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:13 INFO - 'MOZ_NO_REMOTE': '1', 15:07:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:07:13 INFO - 'NO_EM_RESTART': '1', 15:07:13 INFO - 'PAGER': '/bin/cat', 15:07:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:13 INFO - 'PWD': '/builds/slave/test', 15:07:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:13 INFO - 'SHELL': '/bin/bash', 15:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hty3hZBzgJ/Listeners', 15:07:13 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:13 INFO - 'USER': 'cltbld', 15:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:13 INFO - 'XPC_FLAGS': '0x0', 15:07:13 INFO - 'XPC_SERVICE_NAME': '0', 15:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:07:13 INFO - mozversion application_buildid: 20161011121046 15:07:13 INFO - mozversion application_changeset: fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 15:07:13 INFO - mozversion application_display_name: Nightly 15:07:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:07:13 INFO - mozversion application_name: Firefox 15:07:13 INFO - mozversion application_remotingname: firefox 15:07:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:07:13 INFO - mozversion application_vendor: Mozilla 15:07:13 INFO - mozversion application_version: 52.0a1 15:07:13 INFO - mozversion platform_buildid: 20161011121046 15:07:13 INFO - mozversion platform_changeset: fe1d7c28668f0eb07c2f91bd0b3b0fa635d82387 15:07:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:07:13 INFO - mozversion platform_version: 52.0a1 15:07:13 INFO - using testdate: 1476223633 15:07:13 INFO - actual date: 1476223633 15:07:13 INFO - starting webserver on 'localhost:49274' 15:07:13 INFO - SUITE-START | Running 2 tests 15:07:13 INFO - TEST-START | dromaeo_css 15:07:13 INFO - operating with platform_type : mac_ 15:07:13 INFO - Initialising browser for dromaeo_css test... 15:07:13 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpFPPxBH/profile http://localhost:49274/getInfo.html) 15:07:16 INFO - PROCESS | 1644 | 2016-10-11 15:07:16.060 plugin-container[1646:7562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 15:07:16 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:16 INFO - PROCESS | 1644 | 2016-10-11 15:07:16.064 plugin-container[1646:7562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 15:07:16 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:16 INFO - PROCESS | 1644 | 2016-10-11 15:07:16.064 plugin-container[1646:7562] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:07:16 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 15:07:16 INFO - PROCESS | 1644 | colorDepth:24 15:07:16 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 15:07:16 INFO - PROCESS | 1644 | __metrics 15:07:16 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 15:07:16 INFO - TEST-INFO | 1644: exit 0 15:07:16 INFO - Browser initialized. 15:07:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:07:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpFPPxBH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:19 INFO - PROCESS | 1647 | 2016-10-11 15:07:19.453 plugin-container[1648:7677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 15:07:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:19 INFO - PROCESS | 1647 | 2016-10-11 15:07:19.455 plugin-container[1648:7677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 15:07:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:19 INFO - PROCESS | 1647 | 2016-10-11 15:07:19.455 plugin-container[1648:7677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:07:29 INFO - PROCESS | 1647 | RSS: Main: 238821376 15:07:29 INFO - PROCESS | 1647 | 15:08:18 INFO - PROCESS | 1647 | 1476223698801 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:10:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 15:10:34 INFO - PROCESS | 1647 | RSS: Main: 252145664 15:10:34 INFO - PROCESS | 1647 | 15:13:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 15:13:37 INFO - PROCESS | 1647 | RSS: Main: 265404416 15:13:37 INFO - PROCESS | 1647 | 15:16:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 15:16:39 INFO - PROCESS | 1647 | RSS: Main: 269062144 15:16:39 INFO - PROCESS | 1647 | 15:19:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 15:19:42 INFO - PROCESS | 1647 | RSS: Main: 268959744 15:19:42 INFO - PROCESS | 1647 | 15:22:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 15:22:45 INFO - PROCESS | 1647 | RSS: Main: 274333696 15:22:45 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 15:25:48 INFO - PROCESS | 1647 | RSS: Main: 276983808 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | __start_tp_report 15:25:48 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 15:25:48 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 15:25:48 INFO - PROCESS | 1647 | |i|pagename|runs| 15:25:48 INFO - PROCESS | 1647 | |0;dojo.html;3050.252688091418;3327.8518910975704;3369.2419205678716;3388.322335532894;3389.4746314321274;4794.921142742881;5420;5573.665580065194;5600.103983362663;5633.183188437684;5674.687892165929;5766.798162064329;5787.363390027104;5789.681567513785;5826.126081087828;5229.398619158798;5287.180487024518;5303.840884773444;5348.598855085884;5365.919511207335;4792.777841650374;4952.975235123819;5369.704666243355;5376.838694839161;5402.37872644645;5355.3305836770405;5362.034833729927;5363.195520671891;5370.382406023299;5385.973070134643;5336.6264361494905;5350.705711185883;5357.008953343644;5364.6512976656395;5365.248865158881;5192.273081768555;5309.964556911404;5318.813841515567;5326.307580014573;5347.41178470368;5698.715064246771;5751.712414379264;5840.912386314209;5865;5933.228680271537;6079.331273559905;6208.2239720034995;6235.844103897393;6246.125542424064;6249.812505624838;6160.045192995093;6164.907526387108;6168.383161683841;6211.565190436671;6218.347073557302;5005.724685142316;5938.435848594377;5950.285965684146;5954.5534084943365;5969.61197522164;6327.905081423783;6331.2085989251345;6350.523710721714;6357.364263573606;6362.141110949962;5988.580799344091;6070.180525629072;6139.631622102688;6204.782832600928;6250.718717657716;6135.662538560422;6208.317085120633;6211.9689401553605;6232.875342493125;6233.065040244;6143.539234557426;6179.752809887644;6226.501879849601;6236.875262494816;6251.78118765841;6078.149059131725;6145.586515101551;6158.630482171084;6203.7828675996125;6242.469390101892;4926.3842019747535;4945.23348880924;4968.4286307075145;4987.850364489072;5038.042771873333;4411.823527058946;4443.977780111079;4465.799039043186;4472.262076757299;4521.79651915658;5881.5882888198275;5944.970275148597;5957.6127551709;5969.820905372846;5987.401259873977;6095.25698559494;6151.96924015377;6156.6921653917125;6162.969185154046;6199.318075011745;5952.226210592682;5967.343592204854;5972.134040564094;5980.581359304807;5980.820575382745;5359.946400536022;5397.73011349431;5421.864453388618;5440.91838622421;5489.972550137224;5171.63798534099;5389.003034438641;5943.8409510145975;6365.809025729235;6389.552731308764;6124.350421260821;6151.446369826737;6173.876522469435;6179.660118693335;6204;5346.358436987431;6014.736905249946;6165.890139774883;6177.073438984188;6272.937270627235;6070.757169713162;6141.3551577085545;6143.631382117087;6166.105914642262;6190.535709821691;5230.476952304739;5666.433356664301;6094.969525152346;6108.675989703;6138.633639632548;6113.050656958906;6117.705611546597;6119.015143218386;6128.380904898417;6150;6082.273747195213;6120.969395152996;6126.853582554517;6133.487685122301;6141.877162456636;5248.842534723812;5360.839174824761;5661.518770904355;5884.941150588439;5926.851828704317;6097.5731698780655;6167.198264225622;6171.537134715004;6181.381861813783;6186.969065154645;6042.818715438544;6070.42169036045;6102.852830488404;6106.482472871513;6134.04922237054 15:25:48 INFO - PROCESS | 1647 | |1;ext.html;489.58874545381883;503.80351662851496;522.7490804413882;557.3228527339282;563.8195777351247;37870.053248668795;40496.79751601242;40504.99999999996;40612;40613.78158655237;41520.79239603805;42305.15389692204;42312;42321.365179522254;42431;9295.977442481339;9592.952035239814;9605.807883842319;9620.807583848318;9659.855102173473;9495.620175192984;9564.473953932531;9579.712608621752;9585.568649410758;9590.80818383632;9547.856782148274;9566.80866382672;9579.377340472882;9613;9618.711438656852;7389.778306650809;8087.595620218965;8368.958155209186;9047.230985366252;9592.184664303542;7160.606166660831;8051.838963220762;9351.719448416557;9542;9580.425174489483;32469.837650811594;39777.60222397768;39913;39955.800220999;40056;2678;2720.6327145835453;3223.629282632504;3354.1614596350914;3358.286856154365;29384.8530757347;30855.765769369198;31673;31931.680683193103;32027;3202.3755367703307;3255.8372081395837;3285.4743241081314;3292.7695061345717;3408.931821363584;386259;466089;499842.5007874974;502940;505187;60747;60899;60992.695036524536;61039;61128.694356527936;61906.69046654738;62676;62940.68529657414;62952;63143.684281578295;15951.361945522282;16208.75686864698;16210.432634857723;16230.594235143979;16265;12384.56654017105;12438.564650237198;12539.749205015849;12540.247585144738;12554.183978041397;16590.75113873293;17031.744523832152;17037.403690871008;17086.572835679206;17103.315867365167;3151.779375443697;5063.443021267658;6434.7104380303;6556.082148499214;6610.173728283965;843.6625349860105;923.7829110159107;945.621751299472;997.7605374710017;1021.667957913681;29262.561061584092;29726.10821675353;30174.54738178929;30770.846145769126;30777.692223077484;847.877057826624;1061.2995423020923;1068.7488440216543;1095.1731442760665;1191.976160476803;13873.236971966639;15247.16140612277;15537;15676.764848527282;15972.600684982968;657423;660975;666953;668232;670022.6498867573;56659.716701416226;57296;57650.423495765346;57720.71139644275;57858.264252072506;1315.3686230609362;1344.5159742492694;1346.811446397505;1362.870527299905;1370.9862901371057;29331.85334073316;29900.85049574825;30247.125493180796;30408.543871842383;30573.847130764203;50225.74887125541;50574.494255056976;50586.735331617005;50673.493265068355;50880.72798180075;610213;612986;613594;614188.9290553519;614315;23791.69145697003;25914.87042564775;26346.473070538097;26671.33321666974;27039.594406084318;47016;47239.76380118077;47293.527064728914;47959.76020119877;48660.783480411854;39397.60602393939;39461;39631;39733.60266397299;41310.79344603258;32446.21545815003;39891.899410812;40150.79924600475;40476.1904761909;40882.79558602307;34707;38351;38645.806770965966;39033;39222;30768.692313076583;34462.48306275458;34592;34666;34822.47766283457;20869.478263043544;22434;23324;24970.62544061877;26039.609405858548 15:25:48 INFO - PROCESS | 1647 | |2;jquery.html;300.8420579195922;352.8394580465888;366.29122647676746;374.46451574248846;392.8979213306674;18812.43562693118;19395.806041939577;19599.314024009163;20097.29659461919;20328.796712032876;17385.47843564695;18052.819471805276;18880.244790208377;19034.238630454798;19601.117949692263;4717.481077081527;5831.416858314177;5837.1536127261525;5849.502792262663;5863.560232982522;5504.311961004874;5771.913421298684;5812.924608947337;5819.854503637422;5835.387284335147;5473.28847249857;5788.334341550712;5805.767769289223;5806.767729290823;5809.651420914737;5668.206451096849;5720.8855822883515;5755.942440575583;5772.769089236425;5793.594448388614;4941.604671626261;4995.700257984533;5065.290859279704;5067.923981140286;5082.771275292621;24344.147954821496;24388.26835194947;24540.509189816283;24557.508849822905;24647.0141194352;2217.390217690132;2453;2708.471847989643;2756.842126306956;2846.7864910131825;9469.289803264783;9803.950980245054;10148.1374083203;10184.134348580379;14699.897507686966;1815.9818401815944;1836.3572749537689;1851.1484717030048;1861.2648004038329;1898.1742941820214;100968.49515752448;101968.47047294296;103827.46415501836;103841;103927;11144.832827507595;11399.715007124889;11417;11625.53497860093;11673.591424300108;14167.079139856083;14201.076929999517;14240.359183836752;14249.216293103978;14262.930280229024;8112.269895709414;8252.46358986664;8331.583420828934;8421.326293896596;8422.873656895152;8143.79640508992;8167.714130005421;8211.302039326723;8322.6670933162;8609.956950215334;4462.821487140543;4511.571400716926;4540.205464043779;4548.317752337176;4558.931616025762;1659.7925259342583;1878.1923772777836;1935.8257756801956;1958.3831142971042;1972.7965940776114;693.6566399632214;742.9182789893107;821.3634433314229;845.4758050009034;873.2577309287055;19201.51996200078;20678.8626625534;21717.479776415814;21884.23405180811;21924.013419396164;817.2277198047907;862.9007664118582;910.226307638502;945.938513996588;967.260046064761;9855;10759.623413180501;10766.354018758899;10800.1899857511;10846.728831779268;393150;397646.0117699393;398344;628702;638592;133422;135756;135938.4452321544;136980;138176;914.1818072824784;973.0804389851663;974.7222041718077;975.2003357247131;979.1921664626569;15816;15963.600909977344;19010.52473688169;19203.423897283104;19333.709994350378;53333;53616;53676.46323536715;55207;55284.72357638186;58020.068294195466;75886;77440.451190977;77541.61229193817;80250;7874.921250787419;8101.878471823045;8127.634256458356;8197.959010204911;8281;11564.768704626029;12559.058070644556;12595.874041259836;12831.550895718605;12841.844234018983;9809.166220871306;10972.725681858017;11288.774224515628;11329.886701133213;11336.263142895685;10835.241533092609;10853.45732713377;10955.671329860117;11044.889551104707;11253.66239012831;9217.939936907072;11375.146863985068;11471.139664525028;11558.479868405773;11657.968928078591;6868.278830722902;6887.5178737487895;7262.600556969417;7298.890516642148;7542.2834830691;9543.761405964906;10850.186236032172;11193.160512961724;11284.984351408413;11322.773544528898 15:25:48 INFO - PROCESS | 1647 | |3;mootools.html;1326.2838067443581;1507.977380339295;1659.6763631091942;1766.108115401722;1813.229377514556;6489.578177418471;7262.455315851306;7629.66480865849;8811.559422028906;8824.11758824118;6930.445564354852;7434.665440055197;8639.395242333023;8658.91341086589;8741.43180693256;2309.983607212829;2337.1703045418885;2349.1777877742825;2352.1532248390567;2357.634566642165;2306.9884650576723;2328.9184878529254;2339.590571649963;2350.4006478347997;2351.447409858682;2330.3707998840305;2341.9765802342013;2347.565700345433;2350.753170917055;2370.8340416170877;2311.237291693737;2334.6848175496343;2335.602947498921;2336.7195936487647;2342.426105604121;2321.3964369263945;2334.2880421471446;2338.356951838241;2341.5785158671433;2343.7070366204225;8724.825503489894;8738.825223495494;8753.124687531259;8778.956105219497;8814.471131732116;4130.648894843942;5219.217117432378;5271.525562699337;5288.497592728685;5361.624686271963;3520.4543295789194;3573.3032058748554;3662.4689420033947;3664.59689434162;3666.651668091554;1001.5593139018808;1031.1544533482547;1040.3133931605105;1060.6234786650705;1064.340109132335;11253.943730281297;11473.770524589461;11569.826452603218;11573.479193436315;11593.188476806627;4529.977350113294;4604.447466303998;4787.305840653155;4807.567318941312;4815.590674792682;7967.800804979922;9969.501524923724;9973.252006099572;9989.400635961865;10128.797424051478;5314.362276526765;5325.893482130413;5354.277172581729;5359.892802143935;5365.3293338332705;4348.412964249786;4469.687121901436;4528.207563676344;4843.539863712942;4874.780634871439;3389.5424117743787;3878.166194268246;3921.490206273166;4013.177298653827;4045.8988525286513;1278.4694351844066;1282.179405180685;1282.5767496726057;1292.793153095519;1294.1975974895436;847.6694089305176;860.2408075485522;892.4020905993;939.2626787971546;939.7979434421633;5717.371089180187;5876.412358764175;5967;6025.548083893726;6180.6291622502795;416.5126801642086;423.8728381485542;427.9486461624625;944.1596978688972;966.5891995901713;3369.0826011942763;3988.361862102108;4012.1775036117524;4043.5147782265726;4097.426360309559;10377.221708371902;10575.36547807134;10577.788444231226;10661.413622250695;10661.946690266654;10016.449095299828;10529.94700529941;10564.788704226026;10601.787964240671;10652.840207396895;7443.181250062489;7645.732399365996;8192;8198.221168988937;8221;5428.59285553593;5757.769689212385;5939.07944268639;6126.203593532812;6177.135201071764;9595.040495950629;9824.803503930025;10025.298229123891;10059.39643621385;10065.993400659876;10200.3369780964;10213.08082272599;10218.642347517802;10221.211288024715;10231.795364092824;6901.516893817385;7130.144382674112;7370.410367170721;7570.848583028419;7664.80837979055;6157.96921015392;8302.626381812954;8448.366372522209;8452.408331416987;8463.53450560225;7338.229485903903;7434.070741157356;7499.362554182955;7557.697692092254;7586.658600363107;7332.5967071811565;7340.412766978522;7406.07424071991;7486.92513074884;7514.361279291103;6992.405645519984;7007.369336759716;7055.3650171484815;7076.2923707628825;7112.688376277627;4773.04539092176;4820.783064762164;4867.440244371943;4887.291342755209;4922.507749225048;4390.912181756283;4958.677685950402;5080.847574572768;5138.665986710852;5142.562882155059 15:25:48 INFO - PROCESS | 1647 | |4;prototype.html;292.26641130761794;299.52226199212254;305.65766341697304;306.02073365231263;307.6170168140665;4854.854354369375;4865.810385326604;4996.975015124928;5513.842093160436;5608.5793565482545;4913.648746594688;5228.189630607252;5308;5447.863803404917;5450.91823622646;3353.5640366752286;3859.324618191812;4227.85202517912;4236.9576304237025;4263.488381394237;3792.696584273265;3934.4655584322136;3981.2330430350917;4196.454460920091;4237.639800616951;3868.535775706919;3899.395593682984;4145.979270103645;4151.252774500604;4184.8535301264465;3652.707783377337;3805.7145714071557;4156.088199428266;4184.748915065091;4208;3614.204874927512;3640.5085313482605;3641.9453708194133;3647.562292524914;3656.670899619047;4794.688345257547;4861.586765124968;5269.947300526984;5307.416184219733;5374.731263436813;4184.937225941583;4231.957680423187;4325.762083085429;4652.743079844123;4739.241721324572;4687.507811679759;5378.596605254583;5743.195952566644;5767.913481297784;5834.557611009483;2744.588311753233;3189.776715629907;3675.4639298883685;3874;3897.9805100974595;6047.455728984369;6068.605540639888;6076.301225359096;6080.0271956487195;6104.901117798794;3946.309395855714;3956.3274243378696;3962.2075584883137;3972.4835771350117;3972.56301806801;3517.9120521987156;3784.1320184350175;3951.392081226377;4016.196760647882;4027.718059735849;4597.023279989637;4628.4445866496235;4650.441946966385;4667.649926255544;4675.041616468616;5716.771329146871;5763.682997435181;5765.308163020464;5767;5774.740136693775;1260.8991280697521;1306.5361796562083;1319.1491487990193;1335.2455862437691;1359.7824348104257;869.2263885142229;885.8671199320154;907.1835348186685;909.5492689160778;912.466207268742;4982.950170498321;5205.29728486657;5267.26258323835;5411.702356370437;5429.321334833146;5858.677772722456;5886.676232807151;5916.053431451033;5951.226340575698;5952.375000624958;4900.607951363882;5048.747562621854;5200.84397468069;5377.5429088527135;5401.972990135024;2544.9491010179695;2875.7843161762953;3045.9543106853416;3100.8836818745235;3119.0954623159028;5248.553872920768;5763.0202865512965;6157.630542167484;6164.321924588292;6175.84560385994;4865;5380.408155102936;5952.494038006731;6100.816975490742;6105.397815289;5294.814681486207;6079.969600151971;6118.296395914527;6158.922188616974;6196.535259855619;4982.427020892644;5676.20533125371;5823.4467725566;5907.282649980885;5909.045477261369;5375.892482150413;5790.739416726342;5843.802020585855;5873.9412605873395;5922.460160358424;5964.55453264177;6156.199694039746;6183.783567575293;6193.659348735863;6229.2524897012645;3502.2820321834474;3683.3922402803237;3820.3887378019385;3912.980435097806;3955.960440395674;5718.910978650285;5997.4002599741425;6038.909416358671;6039.943009973325;6053.303870055;4471.485779135441;5743.59794814359;5772.278465191851;5833.649981001154;5906.40935906406;5833.82498525045;5930.7627694893445;5942.376050514807;5952.702364881825;5993.943089024716;4554.863354099382;4850.604278358147;5375.220593014069;5598.9082128984455;5717.885642287048;4770.924647778227;5131.307273518177;5809.477147056785;5830.912536312044;5867.383924687846;4799.064182484382;5706.457886500776;5708.429157084258;5745.741441635053;5756.100486839417 15:25:48 INFO - PROCESS | 1647 | |5;yui.html;728.7048745258172;740.3595889555536;761.5811303782918;766.3600893254132;814.9388795840314;14969.251537423126;15277.006994545347;15588.922055389736;15974.600634984132;15976.68046639068;14005.579832605039;14282.500112496065;14285.428582856674;14291.285435728223;14319.856801431983;654.0744846042844;674.2280089297751;825.5500752090095;845.4589062999685;847.1993965702413;814.9062857771344;830.5182993863544;831.9084900660922;842.9968337678174;847.1570787066877;876.1545108969843;880.137465284022;881.3301890563154;882.5940067568926;882.6248844241204;837.9203975622337;872.7381785464368;873.0440168016012;875.4703404440286;876.7588913048899;645.6932956845508;647.6988200486749;649.6264647827518;649.772579597142;650.4568685147907;14528.927355363261;14565.198914059722;14566;14569.635759106;14580.781288280685;11538.365389903553;13789.241591712453;14162.858371416258;14508.27458627075;14534.12795232279;10117.949410252902;10771.192160587987;10859.239853210278;10920;11163.609273675382;3200.647928727838;3647.8326935380687;3751.193493398905;3781.3949768037255;3811.294910441577;93276.06723932741;107866.76399708021;119966;121979.78020219773;123503.76496235012;10734.463276836126;10969.725756855983;11010.50452729629;11042;11149.275297105662;22351.88824055902;22704.864756762097;22732.545349092925;22863.19978800734;22880.885595571915;6669.799906002828;6897.379235868796;7020.789376318617;7817.171511150791;7878.960605196938;3502.2120022994623;4338.631216346583;4346.043870348518;4352.891177720582;4357.172137293904;3211.6467188609236;3509.438489841613;3650.580183278957;3658.067192865802;3663.175785448253;713.9785806425815;714.7712731925837;719.4963525532148;723.5550136665912;732.9963350183215;492.36976669862634;581.8545363659085;648.3095503289077;651.5960104735094;667.506045526307;8081.434299599089;8999.325050621099;9404.341696081208;9422.246220302359;9456.196223320958;579.9623024503394;646.4279112984948;647.1877793369385;668.2882729892649;672.7813460625316;4227.196832601858;4317.460317460317;4510.977445112753;4556.111558246176;4629.909852698225;128767;159760;163694;165514;169015.15492422704;103547.48226258821;108357;109695;144077;146725;100788;101361;106602.46698766456;108143.91856081496;111194.44402777935;8314;9500.334976551852;9614.663486777928;9644.517774111408;9655.951720241354;57972.710136449045;61907.690461547405;62185.75628487495;63351.683241583494;65191.674041629485;69499.65250173716;76598;79163;83204.58397707973;97913;13163.934180329037;13218;13419.060665753304;13530;13865.722685546434;14657.706845863237;14777.335019923914;14880.702385952436;14928.178950157411;14989.400423983354;13562.525311614047;13583.728325433634;13670.11144275619;14054.01621886499;14570.927145364205;13760.587182384545;13788.310584470937;13902.23537705436;13965.790513142108;14328.928355358157;13097.279649618982;13268.800967985295;13475.932620336836;13774.173549587056;13930.79103813464;10722.945666816408;11174.888251117385;11322.490487927898;11474.254173478697;11491.482883270106;7475.887861682188;8869.379143459895;8950.925888893375;8955.731328060168;9814.362066465657 15:25:48 INFO - PROCESS | 1647 | __end_tp_report 15:25:48 INFO - PROCESS | 1647 | __start_cc_report 15:25:48 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,368 15:25:48 INFO - PROCESS | 1647 | __end_cc_report 15:25:48 INFO - PROCESS | 1647 | __startTimestamp1476224748834__endTimestamp 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | ------- Summary: start ------- 15:25:48 INFO - PROCESS | 1647 | Number of tests: 6 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5729.11 Median:5972.13 stddev:632.89 (10.6%) stddev-sans-first:598.92 15:25:48 INFO - PROCESS | 1647 | Values: 3050.3 3327.9 3369.2 3388.3 3389.5 4794.9 5420.0 5573.7 5600.1 5633.2 5674.7 5766.8 5787.4 5789.7 5826.1 5229.4 5287.2 5303.8 5348.6 5365.9 4792.8 4953.0 5369.7 5376.8 5402.4 5355.3 5362.0 5363.2 5370.4 5386.0 5336.6 5350.7 5357.0 5364.7 5365.2 5192.3 5310.0 5318.8 5326.3 5347.4 5698.7 5751.7 5840.9 5865.0 5933.2 6079.3 6208.2 6235.8 6246.1 6249.8 6160.0 6164.9 6168.4 6211.6 6218.3 5005.7 5938.4 5950.3 5954.6 5969.6 6327.9 6331.2 6350.5 6357.4 6362.1 5988.6 6070.2 6139.6 6204.8 6250.7 6135.7 6208.3 6212.0 6232.9 6233.1 6143.5 6179.8 6226.5 6236.9 6251.8 6078.1 6145.6 6158.6 6203.8 6242.5 4926.4 4945.2 4968.4 4987.9 5038.0 4411.8 4444.0 4465.8 4472.3 4521.8 5881.6 5945.0 5957.6 5969.8 5987.4 6095.3 6152.0 6156.7 6163.0 6199.3 5952.2 5967.3 5972.1 5980.6 5980.8 5359.9 5397.7 5421.9 5440.9 5490.0 5171.6 5389.0 5943.8 6365.8 6389.6 6124.4 6151.4 6173.9 6179.7 6204.0 5346.4 6014.7 6165.9 6177.1 6272.9 6070.8 6141.4 6143.6 6166.1 6190.5 5230.5 5666.4 6095.0 6108.7 6138.6 6113.1 6117.7 6119.0 6128.4 6150.0 6082.3 6121.0 6126.9 6133.5 6141.9 5248.8 5360.8 5661.5 5884.9 5926.9 6097.6 6167.2 6171.5 6181.4 6187.0 6042.8 6070.4 6102.9 6106.5 6134.0 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:71218.58 Median:40486.49 stddev:157831.13 (389.8%) stddev-sans-first:158184.55 15:25:48 INFO - PROCESS | 1647 | Values: 489.6 503.8 522.7 557.3 563.8 37870.1 40496.8 40505.0 40612.0 40613.8 41520.8 42305.2 42312.0 42321.4 42431.0 9296.0 9593.0 9605.8 9620.8 9659.9 9495.6 9564.5 9579.7 9585.6 9590.8 9547.9 9566.8 9579.4 9613.0 9618.7 7389.8 8087.6 8369.0 9047.2 9592.2 7160.6 8051.8 9351.7 9542.0 9580.4 32469.8 39777.6 39913.0 39955.8 40056.0 2678.0 2720.6 3223.6 3354.2 3358.3 29384.9 30855.8 31673.0 31931.7 32027.0 3202.4 3255.8 3285.5 3292.8 3408.9 386259.0 466089.0 499842.5 502940.0 505187.0 60747.0 60899.0 60992.7 61039.0 61128.7 61906.7 62676.0 62940.7 62952.0 63143.7 15951.4 16208.8 16210.4 16230.6 16265.0 12384.6 12438.6 12539.7 12540.2 12554.2 16590.8 17031.7 17037.4 17086.6 17103.3 3151.8 5063.4 6434.7 6556.1 6610.2 843.7 923.8 945.6 997.8 1021.7 29262.6 29726.1 30174.5 30770.8 30777.7 847.9 1061.3 1068.7 1095.2 1192.0 13873.2 15247.2 15537.0 15676.8 15972.6 657423.0 660975.0 666953.0 668232.0 670022.6 56659.7 57296.0 57650.4 57720.7 57858.3 1315.4 1344.5 1346.8 1362.9 1371.0 29331.9 29900.9 30247.1 30408.5 30573.8 50225.7 50574.5 50586.7 50673.5 50880.7 610213.0 612986.0 613594.0 614188.9 614315.0 23791.7 25914.9 26346.5 26671.3 27039.6 47016.0 47239.8 47293.5 47959.8 48660.8 39397.6 39461.0 39631.0 39733.6 41310.8 32446.2 39891.9 40150.8 40476.2 40882.8 34707.0 38351.0 38645.8 39033.0 39222.0 30768.7 34462.5 34592.0 34666.0 34822.5 20869.5 22434.0 23324.0 24970.6 26039.6 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:31585.24 Median:359.57 stddev:85267.58 (23714.1%) stddev-sans-first:85474.43 15:25:48 INFO - PROCESS | 1647 | Values: 300.8 352.8 366.3 374.5 392.9 18812.4 19395.8 19599.3 20097.3 20328.8 17385.5 18052.8 18880.2 19034.2 19601.1 4717.5 5831.4 5837.2 5849.5 5863.6 5504.3 5771.9 5812.9 5819.9 5835.4 5473.3 5788.3 5805.8 5806.8 5809.7 5668.2 5720.9 5755.9 5772.8 5793.6 4941.6 4995.7 5065.3 5067.9 5082.8 24344.1 24388.3 24540.5 24557.5 24647.0 2217.4 2453.0 2708.5 2756.8 2846.8 9469.3 9804.0 10148.1 10184.1 14699.9 1816.0 1836.4 1851.1 1861.3 1898.2 100968.5 101968.5 103827.5 103841.0 103927.0 11144.8 11399.7 11417.0 11625.5 11673.6 14167.1 14201.1 14240.4 14249.2 14262.9 8112.3 8252.5 8331.6 8421.3 8422.9 8143.8 8167.7 8211.3 8322.7 8610.0 4462.8 4511.6 4540.2 4548.3 4558.9 1659.8 1878.2 1935.8 1958.4 1972.8 693.7 742.9 821.4 845.5 873.3 19201.5 20678.9 21717.5 21884.2 21924.0 817.2 862.9 910.2 945.9 967.3 9855.0 10759.6 10766.4 10800.2 10846.7 393150.0 397646.0 398344.0 628702.0 638592.0 133422.0 135756.0 135938.4 136980.0 138176.0 914.2 973.1 974.7 975.2 979.2 15816.0 15963.6 19010.5 19203.4 19333.7 53333.0 53616.0 53676.5 55207.0 55284.7 58020.1 75886.0 77440.5 77541.6 80250.0 7874.9 8101.9 8127.6 8198.0 8281.0 11564.8 12559.1 12595.9 12831.6 12841.8 9809.2 10972.7 11288.8 11329.9 11336.3 10835.2 10853.5 10955.7 11044.9 11253.7 9217.9 11375.1 11471.1 11558.5 11658.0 6868.3 6887.5 7262.6 7298.9 7542.3 9543.8 10850.2 11193.2 11285.0 11322.8 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5610.86 Median:4797.44 stddev:3151.20 (65.7%) stddev-sans-first:3143.59 15:25:48 INFO - PROCESS | 1647 | Values: 1326.3 1508.0 1659.7 1766.1 1813.2 6489.6 7262.5 7629.7 8811.6 8824.1 6930.4 7434.7 8639.4 8658.9 8741.4 2310.0 2337.2 2349.2 2352.2 2357.6 2307.0 2328.9 2339.6 2350.4 2351.4 2330.4 2342.0 2347.6 2350.8 2370.8 2311.2 2334.7 2335.6 2336.7 2342.4 2321.4 2334.3 2338.4 2341.6 2343.7 8724.8 8738.8 8753.1 8779.0 8814.5 4130.6 5219.2 5271.5 5288.5 5361.6 3520.5 3573.3 3662.5 3664.6 3666.7 1001.6 1031.2 1040.3 1060.6 1064.3 11253.9 11473.8 11569.8 11573.5 11593.2 4530.0 4604.4 4787.3 4807.6 4815.6 7967.8 9969.5 9973.3 9989.4 10128.8 5314.4 5325.9 5354.3 5359.9 5365.3 4348.4 4469.7 4528.2 4843.5 4874.8 3389.5 3878.2 3921.5 4013.2 4045.9 1278.5 1282.2 1282.6 1292.8 1294.2 847.7 860.2 892.4 939.3 939.8 5717.4 5876.4 5967.0 6025.5 6180.6 416.5 423.9 427.9 944.2 966.6 3369.1 3988.4 4012.2 4043.5 4097.4 10377.2 10575.4 10577.8 10661.4 10661.9 10016.4 10529.9 10564.8 10601.8 10652.8 7443.2 7645.7 8192.0 8198.2 8221.0 5428.6 5757.8 5939.1 6126.2 6177.1 9595.0 9824.8 10025.3 10059.4 10066.0 10200.3 10213.1 10218.6 10221.2 10231.8 6901.5 7130.1 7370.4 7570.8 7664.8 6158.0 8302.6 8448.4 8452.4 8463.5 7338.2 7434.1 7499.4 7557.7 7586.7 7332.6 7340.4 7406.1 7486.9 7514.4 6992.4 7007.4 7055.4 7076.3 7112.7 4773.0 4820.8 4867.4 4887.3 4922.5 4390.9 4958.7 5080.8 5138.7 5142.6 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4648.53 Median:5238.37 stddev:1470.67 (28.1%) stddev-sans-first:1437.99 15:25:48 INFO - PROCESS | 1647 | Values: 292.3 299.5 305.7 306.0 307.6 4854.9 4865.8 4997.0 5513.8 5608.6 4913.6 5228.2 5308.0 5447.9 5450.9 3353.6 3859.3 4227.9 4237.0 4263.5 3792.7 3934.5 3981.2 4196.5 4237.6 3868.5 3899.4 4146.0 4151.3 4184.9 3652.7 3805.7 4156.1 4184.7 4208.0 3614.2 3640.5 3641.9 3647.6 3656.7 4794.7 4861.6 5269.9 5307.4 5374.7 4184.9 4232.0 4325.8 4652.7 4739.2 4687.5 5378.6 5743.2 5767.9 5834.6 2744.6 3189.8 3675.5 3874.0 3898.0 6047.5 6068.6 6076.3 6080.0 6104.9 3946.3 3956.3 3962.2 3972.5 3972.6 3517.9 3784.1 3951.4 4016.2 4027.7 4597.0 4628.4 4650.4 4667.6 4675.0 5716.8 5763.7 5765.3 5767.0 5774.7 1260.9 1306.5 1319.1 1335.2 1359.8 869.2 885.9 907.2 909.5 912.5 4983.0 5205.3 5267.3 5411.7 5429.3 5858.7 5886.7 5916.1 5951.2 5952.4 4900.6 5048.7 5200.8 5377.5 5402.0 2544.9 2875.8 3046.0 3100.9 3119.1 5248.6 5763.0 6157.6 6164.3 6175.8 4865.0 5380.4 5952.5 6100.8 6105.4 5294.8 6080.0 6118.3 6158.9 6196.5 4982.4 5676.2 5823.4 5907.3 5909.0 5375.9 5790.7 5843.8 5873.9 5922.5 5964.6 6156.2 6183.8 6193.7 6229.3 3502.3 3683.4 3820.4 3913.0 3956.0 5718.9 5997.4 6038.9 6039.9 6053.3 4471.5 5743.6 5772.3 5833.6 5906.4 5833.8 5930.8 5942.4 5952.7 5993.9 4554.9 4850.6 5375.2 5598.9 5717.9 4770.9 5131.3 5809.5 5830.9 5867.4 4799.1 5706.5 5708.4 5745.7 5756.1 15:25:48 INFO - PROCESS | 1647 | 15:25:48 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24516.08 Median:3660.62 stddev:39712.59 (1084.9%) stddev-sans-first:39783.84 15:25:48 INFO - PROCESS | 1647 | Values: 728.7 740.4 761.6 766.4 814.9 14969.3 15277.0 15588.9 15974.6 15976.7 14005.6 14282.5 14285.4 14291.3 14319.9 654.1 674.2 825.6 845.5 847.2 814.9 830.5 831.9 843.0 847.2 876.2 880.1 881.3 882.6 882.6 837.9 872.7 873.0 875.5 876.8 645.7 647.7 649.6 649.8 650.5 14528.9 14565.2 14566.0 14569.6 14580.8 11538.4 13789.2 14162.9 14508.3 14534.1 10117.9 10771.2 10859.2 10920.0 11163.6 3200.6 3647.8 3751.2 3781.4 3811.3 93276.1 107866.8 119966.0 121979.8 123503.8 10734.5 10969.7 11010.5 11042.0 11149.3 22351.9 22704.9 22732.5 22863.2 22880.9 6669.8 6897.4 7020.8 7817.2 7879.0 3502.2 4338.6 4346.0 4352.9 4357.2 3211.6 3509.4 3650.6 3658.1 3663.2 714.0 714.8 719.5 723.6 733.0 492.4 581.9 648.3 651.6 667.5 8081.4 8999.3 9404.3 9422.2 9456.2 580.0 646.4 647.2 668.3 672.8 4227.2 4317.5 4511.0 4556.1 4629.9 128767.0 159760.0 163694.0 165514.0 169015.2 103547.5 108357.0 109695.0 144077.0 146725.0 100788.0 101361.0 106602.5 108143.9 111194.4 8314.0 9500.3 9614.7 9644.5 9656.0 57972.7 61907.7 62185.8 63351.7 65191.7 69499.7 76598.0 79163.0 83204.6 97913.0 13163.9 13218.0 13419.1 13530.0 13865.7 14657.7 14777.3 14880.7 14928.2 14989.4 13562.5 13583.7 13670.1 14054.0 14570.9 13760.6 13788.3 13902.2 13965.8 14328.9 13097.3 13268.8 13475.9 13774.2 13930.8 10722.9 11174.9 11322.5 11474.3 11491.5 7475.9 8869.4 8950.9 8955.7 9814.4 15:25:48 INFO - PROCESS | 1647 | -------- Summary: end -------- 15:25:48 INFO - PROCESS | 1647 | 15:25:49 INFO - TEST-INFO | 1647: exit 0 15:25:49 INFO - TEST-OK | dromaeo_css | took 1116308ms 15:25:49 INFO - TEST-START | kraken 15:25:49 INFO - operating with platform_type : mac_ 15:25:49 INFO - Initialising browser for kraken test... 15:25:49 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpTSEX7b/profile http://localhost:49274/getInfo.html) 15:25:52 INFO - PROCESS | 1658 | 2016-10-11 15:25:52.318 plugin-container[1659:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 15:25:52 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:52 INFO - PROCESS | 1658 | 2016-10-11 15:25:52.333 plugin-container[1659:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 15:25:52 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:52 INFO - PROCESS | 1658 | 2016-10-11 15:25:52.333 plugin-container[1659:9787] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:25:52 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 15:25:52 INFO - PROCESS | 1658 | colorDepth:24 15:25:52 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 15:25:52 INFO - PROCESS | 1658 | __metrics 15:25:52 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 15:25:53 INFO - TEST-INFO | 1658: exit 0 15:25:53 INFO - Browser initialized. 15:25:53 INFO - Running cycle 1/1 for kraken test... 15:25:53 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpTSEX7b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:55 INFO - PROCESS | 1660 | 2016-10-11 15:25:55.519 plugin-container[1661:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 15:25:55 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:55 INFO - PROCESS | 1660 | 2016-10-11 15:25:55.520 plugin-container[1661:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 15:25:55 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:55 INFO - PROCESS | 1660 | 2016-10-11 15:25:55.520 plugin-container[1661:9907] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:26:05 INFO - PROCESS | 1660 | RSS: Main: 238948352 15:26:05 INFO - PROCESS | 1660 | 15:26:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 15:26:09 INFO - PROCESS | 1660 | RSS: Main: 252243968 15:26:09 INFO - PROCESS | 1660 | 15:26:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 15:26:12 INFO - PROCESS | 1660 | RSS: Main: 259743744 15:26:12 INFO - PROCESS | 1660 | 15:26:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 15:26:16 INFO - PROCESS | 1660 | RSS: Main: 260325376 15:26:16 INFO - PROCESS | 1660 | 15:26:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 15:26:19 INFO - PROCESS | 1660 | RSS: Main: 263008256 15:26:19 INFO - PROCESS | 1660 | 15:26:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:26:21 INFO - PROCESS | 1660 | RSS: Main: 262815744 15:26:21 INFO - PROCESS | 1660 | 15:26:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 15:26:25 INFO - PROCESS | 1660 | RSS: Main: 263540736 15:26:25 INFO - PROCESS | 1660 | 15:26:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 15:26:28 INFO - PROCESS | 1660 | RSS: Main: 263962624 15:26:28 INFO - PROCESS | 1660 | 15:26:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 15:26:32 INFO - PROCESS | 1660 | RSS: Main: 264171520 15:26:32 INFO - PROCESS | 1660 | 15:26:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:26:34 INFO - PROCESS | 1660 | RSS: Main: 263876608 15:26:34 INFO - PROCESS | 1660 | 15:26:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:26:37 INFO - PROCESS | 1660 | RSS: Main: 264065024 15:26:37 INFO - PROCESS | 1660 | 15:26:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:26:40 INFO - PROCESS | 1660 | RSS: Main: 263974912 15:26:40 INFO - PROCESS | 1660 | 15:26:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:26:43 INFO - PROCESS | 1660 | RSS: Main: 264175616 15:26:43 INFO - PROCESS | 1660 | 15:26:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:26:47 INFO - PROCESS | 1660 | RSS: Main: 264228864 15:26:47 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 15:26:49 INFO - PROCESS | 1660 | RSS: Main: 265965568 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | __start_tp_report 15:26:49 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 15:26:49 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 15:26:49 INFO - PROCESS | 1660 | |i|pagename|runs| 15:26:49 INFO - PROCESS | 1660 | |0;ai-astar;106;114;117;118;116;118;118;117;117;119 15:26:49 INFO - PROCESS | 1660 | |1;audio-beat-detection;146;118;106;167;144;143;146;146;136;143 15:26:49 INFO - PROCESS | 1660 | |2;audio-dft;246;267;254;194;196;202;199;203;216;204 15:26:49 INFO - PROCESS | 1660 | |3;audio-fft;80;59;58;85;62;65;66;80;81;59 15:26:49 INFO - PROCESS | 1660 | |4;audio-oscillator;85;70;87;71;69;69;90;70;72;86 15:26:49 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;92;89;92;89;92;88;92;90;91;91 15:26:49 INFO - PROCESS | 1660 | |6;imaging-darkroom;101;126;102;101;117;138;100;100;100;102 15:26:49 INFO - PROCESS | 1660 | |7;imaging-desaturate;109;118;93;91;95;91;91;121;89;154 15:26:49 INFO - PROCESS | 1660 | |8;json-parse-financial;57;56;55;57;57;58;58;59;56;56 15:26:49 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;69;70;76;84;78;74;71;71;70;70 15:26:49 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;88;87;91;108;108;111;88;87;89;89 15:26:49 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;148;150;145;143;148;170;140;148;191;148 15:26:49 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;173;175;185;175;173;174;195;173;241;236 15:26:49 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;70;69;71;69;71;71;69;68;70;72 15:26:49 INFO - PROCESS | 1660 | __end_tp_report 15:26:49 INFO - PROCESS | 1660 | __start_cc_report 15:26:49 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,990 15:26:49 INFO - PROCESS | 1660 | __end_cc_report 15:26:49 INFO - PROCESS | 1660 | __startTimestamp1476224809317__endTimestamp 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | ------- Summary: start ------- 15:26:49 INFO - PROCESS | 1660 | Number of tests: 14 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.50 stddev:3.77 (3.2%) stddev-sans-first:1.45 15:26:49 INFO - PROCESS | 1660 | Values: 106.0 114.0 117.0 118.0 116.0 118.0 118.0 117.0 117.0 119.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:145.00 stddev:16.75 (11.6%) stddev-sans-first:17.60 15:26:49 INFO - PROCESS | 1660 | Values: 146.0 118.0 106.0 167.0 144.0 143.0 146.0 146.0 136.0 143.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:218.10 Median:210.00 stddev:27.05 (12.9%) stddev-sans-first:26.73 15:26:49 INFO - PROCESS | 1660 | Values: 246.0 267.0 254.0 194.0 196.0 202.0 199.0 203.0 216.0 204.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:69.50 Median:73.00 stddev:10.72 (14.7%) stddev-sans-first:10.68 15:26:49 INFO - PROCESS | 1660 | Values: 80.0 59.0 58.0 85.0 62.0 65.0 66.0 80.0 81.0 59.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.50 stddev:8.82 (11.2%) stddev-sans-first:8.86 15:26:49 INFO - PROCESS | 1660 | Values: 85.0 70.0 87.0 71.0 69.0 69.0 90.0 70.0 72.0 86.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:91.50 stddev:1.51 (1.6%) stddev-sans-first:1.51 15:26:49 INFO - PROCESS | 1660 | Values: 92.0 89.0 92.0 89.0 92.0 88.0 92.0 90.0 91.0 91.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:102.00 stddev:13.59 (13.3%) stddev-sans-first:14.13 15:26:49 INFO - PROCESS | 1660 | Values: 101.0 126.0 102.0 101.0 117.0 138.0 100.0 100.0 100.0 102.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:91.00 stddev:20.90 (23.0%) stddev-sans-first:22.12 15:26:49 INFO - PROCESS | 1660 | Values: 109.0 118.0 93.0 91.0 95.0 91.0 91.0 121.0 89.0 154.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.20 (2.1%) stddev-sans-first:1.27 15:26:49 INFO - PROCESS | 1660 | Values: 57.0 56.0 55.0 57.0 57.0 58.0 58.0 59.0 56.0 56.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:72.50 stddev:4.79 (6.6%) stddev-sans-first:4.82 15:26:49 INFO - PROCESS | 1660 | Values: 69.0 70.0 76.0 84.0 78.0 74.0 71.0 71.0 70.0 70.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:88.00 stddev:10.04 (11.4%) stddev-sans-first:10.36 15:26:49 INFO - PROCESS | 1660 | Values: 88.0 87.0 91.0 108.0 108.0 111.0 88.0 87.0 89.0 89.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:148.00 stddev:15.55 (10.5%) stddev-sans-first:16.38 15:26:49 INFO - PROCESS | 1660 | Values: 148.0 150.0 145.0 143.0 148.0 170.0 140.0 148.0 191.0 148.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:180.00 stddev:26.54 (14.7%) stddev-sans-first:27.43 15:26:49 INFO - PROCESS | 1660 | Values: 173.0 175.0 185.0 175.0 173.0 174.0 195.0 173.0 241.0 236.0 15:26:49 INFO - PROCESS | 1660 | 15:26:49 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.50 stddev:1.25 (1.8%) stddev-sans-first:1.32 15:26:49 INFO - PROCESS | 1660 | Values: 70.0 69.0 71.0 69.0 71.0 71.0 69.0 68.0 70.0 72.0 15:26:49 INFO - PROCESS | 1660 | -------- Summary: end -------- 15:26:49 INFO - PROCESS | 1660 | 15:26:50 INFO - TEST-INFO | 1660: exit 0 15:26:50 INFO - TEST-OK | kraken | took 60600ms 15:26:50 INFO - SUITE-END | took 1176s 15:26:50 INFO - Completed test suite (00:19:37) 15:26:50 INFO - javascript benchmark 15:26:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7276.337973940678, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3050.252688091418, 3327.8518910975704, 3369.2419205678716, 3388.322335532894, 3389.4746314321274, 4794.921142742881, 5420.0, 5573.665580065194, 5600.103983362663, 5633.183188437684, 5674.687892165929, 5766.798162064329, 5787.363390027104, 5789.681567513785, 5826.126081087828, 5229.398619158798, 5287.180487024518, 5303.840884773444, 5348.598855085884, 5365.919511207335, 4792.777841650374, 4952.975235123819, 5369.704666243355, 5376.838694839161, 5402.37872644645, 5355.3305836770405, 5362.034833729927, 5363.195520671891, 5370.382406023299, 5385.973070134643, 5336.6264361494905, 5350.705711185883, 5357.008953343644, 5364.6512976656395, 5365.248865158881, 5192.273081768555, 5309.964556911404, 5318.813841515567, 5326.307580014573, 5347.41178470368, 5698.715064246771, 5751.712414379264, 5840.912386314209, 5865.0, 5933.228680271537, 6079.331273559905, 6208.2239720034995, 6235.844103897393, 6246.125542424064, 6249.812505624838, 6160.045192995093, 6164.907526387108, 6168.383161683841, 6211.565190436671, 6218.347073557302, 5005.724685142316, 5938.435848594377, 5950.285965684146, 5954.5534084943365, 5969.61197522164, 6327.905081423783, 6331.2085989251345, 6350.523710721714, 6357.364263573606, 6362.141110949962, 5988.580799344091, 6070.180525629072, 6139.631622102688, 6204.782832600928, 6250.718717657716, 6135.662538560422, 6208.317085120633, 6211.9689401553605, 6232.875342493125, 6233.065040244, 6143.539234557426, 6179.752809887644, 6226.501879849601, 6236.875262494816, 6251.78118765841, 6078.149059131725, 6145.586515101551, 6158.630482171084, 6203.7828675996125, 6242.469390101892, 4926.3842019747535, 4945.23348880924, 4968.4286307075145, 4987.850364489072, 5038.042771873333, 4411.823527058946, 4443.977780111079, 4465.799039043186, 4472.262076757299, 4521.79651915658, 5881.5882888198275, 5944.970275148597, 5957.6127551709, 5969.820905372846, 5987.401259873977, 6095.25698559494, 6151.96924015377, 6156.6921653917125, 6162.969185154046, 6199.318075011745, 5952.226210592682, 5967.343592204854, 5972.134040564094, 5980.581359304807, 5980.820575382745, 5359.946400536022, 5397.73011349431, 5421.864453388618, 5440.91838622421, 5489.972550137224, 5171.63798534099, 5389.003034438641, 5943.8409510145975, 6365.809025729235, 6389.552731308764, 6124.350421260821, 6151.446369826737, 6173.876522469435, 6179.660118693335, 6204.0, 5346.358436987431, 6014.736905249946, 6165.890139774883, 6177.073438984188, 6272.937270627235, 6070.757169713162, 6141.3551577085545, 6143.631382117087, 6166.105914642262, 6190.535709821691, 5230.476952304739, 5666.433356664301, 6094.969525152346, 6108.675989703, 6138.633639632548, 6113.050656958906, 6117.705611546597, 6119.015143218386, 6128.380904898417, 6150.0, 6082.273747195213, 6120.969395152996, 6126.853582554517, 6133.487685122301, 6141.877162456636, 5248.842534723812, 5360.839174824761, 5661.518770904355, 5884.941150588439, 5926.851828704317, 6097.5731698780655, 6167.198264225622, 6171.537134715004, 6181.381861813783, 6186.969065154645, 6042.818715438544, 6070.42169036045, 6102.852830488404, 6106.482472871513, 6134.04922237054], "value": 5689.786542558615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.58874545381883, 503.80351662851496, 522.7490804413882, 557.3228527339282, 563.8195777351247, 37870.053248668795, 40496.79751601242, 40504.99999999996, 40612.0, 40613.78158655237, 41520.79239603805, 42305.15389692204, 42312.0, 42321.365179522254, 42431.0, 9295.977442481339, 9592.952035239814, 9605.807883842319, 9620.807583848318, 9659.855102173473, 9495.620175192984, 9564.473953932531, 9579.712608621752, 9585.568649410758, 9590.80818383632, 9547.856782148274, 9566.80866382672, 9579.377340472882, 9613.0, 9618.711438656852, 7389.778306650809, 8087.595620218965, 8368.958155209186, 9047.230985366252, 9592.184664303542, 7160.606166660831, 8051.838963220762, 9351.719448416557, 9542.0, 9580.425174489483, 32469.837650811594, 39777.60222397768, 39913.0, 39955.800220999, 40056.0, 2678.0, 2720.6327145835453, 3223.629282632504, 3354.1614596350914, 3358.286856154365, 29384.8530757347, 30855.765769369198, 31673.0, 31931.680683193103, 32027.0, 3202.3755367703307, 3255.8372081395837, 3285.4743241081314, 3292.7695061345717, 3408.931821363584, 386259.0, 466089.0, 499842.5007874974, 502940.0, 505187.0, 60747.0, 60899.0, 60992.695036524536, 61039.0, 61128.694356527936, 61906.69046654738, 62676.0, 62940.68529657414, 62952.0, 63143.684281578295, 15951.361945522282, 16208.75686864698, 16210.432634857723, 16230.594235143979, 16265.0, 12384.56654017105, 12438.564650237198, 12539.749205015849, 12540.247585144738, 12554.183978041397, 16590.75113873293, 17031.744523832152, 17037.403690871008, 17086.572835679206, 17103.315867365167, 3151.779375443697, 5063.443021267658, 6434.7104380303, 6556.082148499214, 6610.173728283965, 843.6625349860105, 923.7829110159107, 945.621751299472, 997.7605374710017, 1021.667957913681, 29262.561061584092, 29726.10821675353, 30174.54738178929, 30770.846145769126, 30777.692223077484, 847.877057826624, 1061.2995423020923, 1068.7488440216543, 1095.1731442760665, 1191.976160476803, 13873.236971966639, 15247.16140612277, 15537.0, 15676.764848527282, 15972.600684982968, 657423.0, 660975.0, 666953.0, 668232.0, 670022.6498867573, 56659.716701416226, 57296.0, 57650.423495765346, 57720.71139644275, 57858.264252072506, 1315.3686230609362, 1344.5159742492694, 1346.811446397505, 1362.870527299905, 1370.9862901371057, 29331.85334073316, 29900.85049574825, 30247.125493180796, 30408.543871842383, 30573.847130764203, 50225.74887125541, 50574.494255056976, 50586.735331617005, 50673.493265068355, 50880.72798180075, 610213.0, 612986.0, 613594.0, 614188.9290553519, 614315.0, 23791.69145697003, 25914.87042564775, 26346.473070538097, 26671.33321666974, 27039.594406084318, 47016.0, 47239.76380118077, 47293.527064728914, 47959.76020119877, 48660.783480411854, 39397.60602393939, 39461.0, 39631.0, 39733.60266397299, 41310.79344603258, 32446.21545815003, 39891.899410812, 40150.79924600475, 40476.1904761909, 40882.79558602307, 34707.0, 38351.0, 38645.806770965966, 39033.0, 39222.0, 30768.692313076583, 34462.48306275458, 34592.0, 34666.0, 34822.47766283457, 20869.478263043544, 22434.0, 23324.0, 24970.62544061877, 26039.609405858548], "value": 19560.241395486584, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8420579195922, 352.8394580465888, 366.29122647676746, 374.46451574248846, 392.8979213306674, 18812.43562693118, 19395.806041939577, 19599.314024009163, 20097.29659461919, 20328.796712032876, 17385.47843564695, 18052.819471805276, 18880.244790208377, 19034.238630454798, 19601.117949692263, 4717.481077081527, 5831.416858314177, 5837.1536127261525, 5849.502792262663, 5863.560232982522, 5504.311961004874, 5771.913421298684, 5812.924608947337, 5819.854503637422, 5835.387284335147, 5473.28847249857, 5788.334341550712, 5805.767769289223, 5806.767729290823, 5809.651420914737, 5668.206451096849, 5720.8855822883515, 5755.942440575583, 5772.769089236425, 5793.594448388614, 4941.604671626261, 4995.700257984533, 5065.290859279704, 5067.923981140286, 5082.771275292621, 24344.147954821496, 24388.26835194947, 24540.509189816283, 24557.508849822905, 24647.0141194352, 2217.390217690132, 2453.0, 2708.471847989643, 2756.842126306956, 2846.7864910131825, 9469.289803264783, 9803.950980245054, 10148.1374083203, 10184.134348580379, 14699.897507686966, 1815.9818401815944, 1836.3572749537689, 1851.1484717030048, 1861.2648004038329, 1898.1742941820214, 100968.49515752448, 101968.47047294296, 103827.46415501836, 103841.0, 103927.0, 11144.832827507595, 11399.715007124889, 11417.0, 11625.53497860093, 11673.591424300108, 14167.079139856083, 14201.076929999517, 14240.359183836752, 14249.216293103978, 14262.930280229024, 8112.269895709414, 8252.46358986664, 8331.583420828934, 8421.326293896596, 8422.873656895152, 8143.79640508992, 8167.714130005421, 8211.302039326723, 8322.6670933162, 8609.956950215334, 4462.821487140543, 4511.571400716926, 4540.205464043779, 4548.317752337176, 4558.931616025762, 1659.7925259342583, 1878.1923772777836, 1935.8257756801956, 1958.3831142971042, 1972.7965940776114, 693.6566399632214, 742.9182789893107, 821.3634433314229, 845.4758050009034, 873.2577309287055, 19201.51996200078, 20678.8626625534, 21717.479776415814, 21884.23405180811, 21924.013419396164, 817.2277198047907, 862.9007664118582, 910.226307638502, 945.938513996588, 967.260046064761, 9855.0, 10759.623413180501, 10766.354018758899, 10800.1899857511, 10846.728831779268, 393150.0, 397646.0117699393, 398344.0, 628702.0, 638592.0, 133422.0, 135756.0, 135938.4452321544, 136980.0, 138176.0, 914.1818072824784, 973.0804389851663, 974.7222041718077, 975.2003357247131, 979.1921664626569, 15816.0, 15963.600909977344, 19010.52473688169, 19203.423897283104, 19333.709994350378, 53333.0, 53616.0, 53676.46323536715, 55207.0, 55284.72357638186, 58020.068294195466, 75886.0, 77440.451190977, 77541.61229193817, 80250.0, 7874.921250787419, 8101.878471823045, 8127.634256458356, 8197.959010204911, 8281.0, 11564.768704626029, 12559.058070644556, 12595.874041259836, 12831.550895718605, 12841.844234018983, 9809.166220871306, 10972.725681858017, 11288.774224515628, 11329.886701133213, 11336.263142895685, 10835.241533092609, 10853.45732713377, 10955.671329860117, 11044.889551104707, 11253.66239012831, 9217.939936907072, 11375.146863985068, 11471.139664525028, 11558.479868405773, 11657.968928078591, 6868.278830722902, 6887.5178737487895, 7262.600556969417, 7298.890516642148, 7542.2834830691, 9543.761405964906, 10850.186236032172, 11193.160512961724, 11284.984351408413, 11322.773544528898], "value": 9281.74683437966, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.2838067443581, 1507.977380339295, 1659.6763631091942, 1766.108115401722, 1813.229377514556, 6489.578177418471, 7262.455315851306, 7629.66480865849, 8811.559422028906, 8824.11758824118, 6930.445564354852, 7434.665440055197, 8639.395242333023, 8658.91341086589, 8741.43180693256, 2309.983607212829, 2337.1703045418885, 2349.1777877742825, 2352.1532248390567, 2357.634566642165, 2306.9884650576723, 2328.9184878529254, 2339.590571649963, 2350.4006478347997, 2351.447409858682, 2330.3707998840305, 2341.9765802342013, 2347.565700345433, 2350.753170917055, 2370.8340416170877, 2311.237291693737, 2334.6848175496343, 2335.602947498921, 2336.7195936487647, 2342.426105604121, 2321.3964369263945, 2334.2880421471446, 2338.356951838241, 2341.5785158671433, 2343.7070366204225, 8724.825503489894, 8738.825223495494, 8753.124687531259, 8778.956105219497, 8814.471131732116, 4130.648894843942, 5219.217117432378, 5271.525562699337, 5288.497592728685, 5361.624686271963, 3520.4543295789194, 3573.3032058748554, 3662.4689420033947, 3664.59689434162, 3666.651668091554, 1001.5593139018808, 1031.1544533482547, 1040.3133931605105, 1060.6234786650705, 1064.340109132335, 11253.943730281297, 11473.770524589461, 11569.826452603218, 11573.479193436315, 11593.188476806627, 4529.977350113294, 4604.447466303998, 4787.305840653155, 4807.567318941312, 4815.590674792682, 7967.800804979922, 9969.501524923724, 9973.252006099572, 9989.400635961865, 10128.797424051478, 5314.362276526765, 5325.893482130413, 5354.277172581729, 5359.892802143935, 5365.3293338332705, 4348.412964249786, 4469.687121901436, 4528.207563676344, 4843.539863712942, 4874.780634871439, 3389.5424117743787, 3878.166194268246, 3921.490206273166, 4013.177298653827, 4045.8988525286513, 1278.4694351844066, 1282.179405180685, 1282.5767496726057, 1292.793153095519, 1294.1975974895436, 847.6694089305176, 860.2408075485522, 892.4020905993, 939.2626787971546, 939.7979434421633, 5717.371089180187, 5876.412358764175, 5967.0, 6025.548083893726, 6180.6291622502795, 416.5126801642086, 423.8728381485542, 427.9486461624625, 944.1596978688972, 966.5891995901713, 3369.0826011942763, 3988.361862102108, 4012.1775036117524, 4043.5147782265726, 4097.426360309559, 10377.221708371902, 10575.36547807134, 10577.788444231226, 10661.413622250695, 10661.946690266654, 10016.449095299828, 10529.94700529941, 10564.788704226026, 10601.787964240671, 10652.840207396895, 7443.181250062489, 7645.732399365996, 8192.0, 8198.221168988937, 8221.0, 5428.59285553593, 5757.769689212385, 5939.07944268639, 6126.203593532812, 6177.135201071764, 9595.040495950629, 9824.803503930025, 10025.298229123891, 10059.39643621385, 10065.993400659876, 10200.3369780964, 10213.08082272599, 10218.642347517802, 10221.211288024715, 10231.795364092824, 6901.516893817385, 7130.144382674112, 7370.410367170721, 7570.848583028419, 7664.80837979055, 6157.96921015392, 8302.626381812954, 8448.366372522209, 8452.408331416987, 8463.53450560225, 7338.229485903903, 7434.070741157356, 7499.362554182955, 7557.697692092254, 7586.658600363107, 7332.5967071811565, 7340.412766978522, 7406.07424071991, 7486.92513074884, 7514.361279291103, 6992.405645519984, 7007.369336759716, 7055.3650171484815, 7076.2923707628825, 7112.688376277627, 4773.04539092176, 4820.783064762164, 4867.440244371943, 4887.291342755209, 4922.507749225048, 4390.912181756283, 4958.677685950402, 5080.847574572768, 5138.665986710852, 5142.562882155059], "value": 4483.310590346453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.26641130761794, 299.52226199212254, 305.65766341697304, 306.02073365231263, 307.6170168140665, 4854.854354369375, 4865.810385326604, 4996.975015124928, 5513.842093160436, 5608.5793565482545, 4913.648746594688, 5228.189630607252, 5308.0, 5447.863803404917, 5450.91823622646, 3353.5640366752286, 3859.324618191812, 4227.85202517912, 4236.9576304237025, 4263.488381394237, 3792.696584273265, 3934.4655584322136, 3981.2330430350917, 4196.454460920091, 4237.639800616951, 3868.535775706919, 3899.395593682984, 4145.979270103645, 4151.252774500604, 4184.8535301264465, 3652.707783377337, 3805.7145714071557, 4156.088199428266, 4184.748915065091, 4208.0, 3614.204874927512, 3640.5085313482605, 3641.9453708194133, 3647.562292524914, 3656.670899619047, 4794.688345257547, 4861.586765124968, 5269.947300526984, 5307.416184219733, 5374.731263436813, 4184.937225941583, 4231.957680423187, 4325.762083085429, 4652.743079844123, 4739.241721324572, 4687.507811679759, 5378.596605254583, 5743.195952566644, 5767.913481297784, 5834.557611009483, 2744.588311753233, 3189.776715629907, 3675.4639298883685, 3874.0, 3897.9805100974595, 6047.455728984369, 6068.605540639888, 6076.301225359096, 6080.0271956487195, 6104.901117798794, 3946.309395855714, 3956.3274243378696, 3962.2075584883137, 3972.4835771350117, 3972.56301806801, 3517.9120521987156, 3784.1320184350175, 3951.392081226377, 4016.196760647882, 4027.718059735849, 4597.023279989637, 4628.4445866496235, 4650.441946966385, 4667.649926255544, 4675.041616468616, 5716.771329146871, 5763.682997435181, 5765.308163020464, 5767.0, 5774.740136693775, 1260.8991280697521, 1306.5361796562083, 1319.1491487990193, 1335.2455862437691, 1359.7824348104257, 869.2263885142229, 885.8671199320154, 907.1835348186685, 909.5492689160778, 912.466207268742, 4982.950170498321, 5205.29728486657, 5267.26258323835, 5411.702356370437, 5429.321334833146, 5858.677772722456, 5886.676232807151, 5916.053431451033, 5951.226340575698, 5952.375000624958, 4900.607951363882, 5048.747562621854, 5200.84397468069, 5377.5429088527135, 5401.972990135024, 2544.9491010179695, 2875.7843161762953, 3045.9543106853416, 3100.8836818745235, 3119.0954623159028, 5248.553872920768, 5763.0202865512965, 6157.630542167484, 6164.321924588292, 6175.84560385994, 4865.0, 5380.408155102936, 5952.494038006731, 6100.816975490742, 6105.397815289, 5294.814681486207, 6079.969600151971, 6118.296395914527, 6158.922188616974, 6196.535259855619, 4982.427020892644, 5676.20533125371, 5823.4467725566, 5907.282649980885, 5909.045477261369, 5375.892482150413, 5790.739416726342, 5843.802020585855, 5873.9412605873395, 5922.460160358424, 5964.55453264177, 6156.199694039746, 6183.783567575293, 6193.659348735863, 6229.2524897012645, 3502.2820321834474, 3683.3922402803237, 3820.3887378019385, 3912.980435097806, 3955.960440395674, 5718.910978650285, 5997.4002599741425, 6038.909416358671, 6039.943009973325, 6053.303870055, 4471.485779135441, 5743.59794814359, 5772.278465191851, 5833.649981001154, 5906.40935906406, 5833.82498525045, 5930.7627694893445, 5942.376050514807, 5952.702364881825, 5993.943089024716, 4554.863354099382, 4850.604278358147, 5375.220593014069, 5598.9082128984455, 5717.885642287048, 4770.924647778227, 5131.307273518177, 5809.477147056785, 5830.912536312044, 5867.383924687846, 4799.064182484382, 5706.457886500776, 5708.429157084258, 5745.741441635053, 5756.100486839417], "value": 4175.480814351961, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.7048745258172, 740.3595889555536, 761.5811303782918, 766.3600893254132, 814.9388795840314, 14969.251537423126, 15277.006994545347, 15588.922055389736, 15974.600634984132, 15976.68046639068, 14005.579832605039, 14282.500112496065, 14285.428582856674, 14291.285435728223, 14319.856801431983, 654.0744846042844, 674.2280089297751, 825.5500752090095, 845.4589062999685, 847.1993965702413, 814.9062857771344, 830.5182993863544, 831.9084900660922, 842.9968337678174, 847.1570787066877, 876.1545108969843, 880.137465284022, 881.3301890563154, 882.5940067568926, 882.6248844241204, 837.9203975622337, 872.7381785464368, 873.0440168016012, 875.4703404440286, 876.7588913048899, 645.6932956845508, 647.6988200486749, 649.6264647827518, 649.772579597142, 650.4568685147907, 14528.927355363261, 14565.198914059722, 14566.0, 14569.635759106, 14580.781288280685, 11538.365389903553, 13789.241591712453, 14162.858371416258, 14508.27458627075, 14534.12795232279, 10117.949410252902, 10771.192160587987, 10859.239853210278, 10920.0, 11163.609273675382, 3200.647928727838, 3647.8326935380687, 3751.193493398905, 3781.3949768037255, 3811.294910441577, 93276.06723932741, 107866.76399708021, 119966.0, 121979.78020219773, 123503.76496235012, 10734.463276836126, 10969.725756855983, 11010.50452729629, 11042.0, 11149.275297105662, 22351.88824055902, 22704.864756762097, 22732.545349092925, 22863.19978800734, 22880.885595571915, 6669.799906002828, 6897.379235868796, 7020.789376318617, 7817.171511150791, 7878.960605196938, 3502.2120022994623, 4338.631216346583, 4346.043870348518, 4352.891177720582, 4357.172137293904, 3211.6467188609236, 3509.438489841613, 3650.580183278957, 3658.067192865802, 3663.175785448253, 713.9785806425815, 714.7712731925837, 719.4963525532148, 723.5550136665912, 732.9963350183215, 492.36976669862634, 581.8545363659085, 648.3095503289077, 651.5960104735094, 667.506045526307, 8081.434299599089, 8999.325050621099, 9404.341696081208, 9422.246220302359, 9456.196223320958, 579.9623024503394, 646.4279112984948, 647.1877793369385, 668.2882729892649, 672.7813460625316, 4227.196832601858, 4317.460317460317, 4510.977445112753, 4556.111558246176, 4629.909852698225, 128767.0, 159760.0, 163694.0, 165514.0, 169015.15492422704, 103547.48226258821, 108357.0, 109695.0, 144077.0, 146725.0, 100788.0, 101361.0, 106602.46698766456, 108143.91856081496, 111194.44402777935, 8314.0, 9500.334976551852, 9614.663486777928, 9644.517774111408, 9655.951720241354, 57972.710136449045, 61907.690461547405, 62185.75628487495, 63351.683241583494, 65191.674041629485, 69499.65250173716, 76598.0, 79163.0, 83204.58397707973, 97913.0, 13163.934180329037, 13218.0, 13419.060665753304, 13530.0, 13865.722685546434, 14657.706845863237, 14777.335019923914, 14880.702385952436, 14928.178950157411, 14989.400423983354, 13562.525311614047, 13583.728325433634, 13670.11144275619, 14054.01621886499, 14570.927145364205, 13760.587182384545, 13788.310584470937, 13902.23537705436, 13965.790513142108, 14328.928355358157, 13097.279649618982, 13268.800967985295, 13475.932620336836, 13774.173549587056, 13930.79103813464, 10722.945666816408, 11174.888251117385, 11322.490487927898, 11474.254173478697, 11491.482883270106, 7475.887861682188, 8869.379143459895, 8950.925888893375, 8955.731328060168, 9814.362066465657], "value": 7674.097109695541, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 117.0, 118.0, 116.0, 118.0, 118.0, 117.0, 117.0, 119.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 106.0, 167.0, 144.0, 143.0, 146.0, 146.0, 136.0, 143.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [246.0, 267.0, 254.0, 194.0, 196.0, 202.0, 199.0, 203.0, 216.0, 204.0], "value": 218.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 85.0, 62.0, 65.0, 66.0, 80.0, 81.0, 59.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 87.0, 71.0, 69.0, 69.0, 90.0, 70.0, 72.0, 86.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 89.0, 92.0, 88.0, 92.0, 90.0, 91.0, 91.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 126.0, 102.0, 101.0, 117.0, 138.0, 100.0, 100.0, 100.0, 102.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 93.0, 91.0, 95.0, 91.0, 91.0, 121.0, 89.0, 154.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 57.0, 57.0, 58.0, 58.0, 59.0, 56.0, 56.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 76.0, 84.0, 78.0, 74.0, 71.0, 71.0, 70.0, 70.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 91.0, 108.0, 108.0, 111.0, 88.0, 87.0, 89.0, 89.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 145.0, 143.0, 148.0, 170.0, 140.0, 148.0, 191.0, 148.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 185.0, 175.0, 173.0, 174.0, 195.0, 173.0, 241.0, 236.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 69.0, 71.0, 71.0, 69.0, 68.0, 70.0, 72.0], "value": 70.0, "unit": "score"}]}]} 15:26:50 INFO - Return code: 0 15:26:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:26:50 ERROR - # TBPL SUCCESS # 15:26:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:26:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:26:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:26:50 INFO - 2016-10-11 15:26:50.623 screenresolution[1665:10281] starting screenresolution argv=screenresolution get 15:26:50 INFO - 2016-10-11 15:26:50.626 screenresolution[1665:10281] Display 0: 1600x1200x32@60 15:26:50 INFO - 2016-10-11 15:26:50.632 screenresolution[1666:10285] starting screenresolution argv=screenresolution list 15:26:50 INFO - Available Modes on Display 0 15:26:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:26:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:26:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:26:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:26:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:26:50 INFO - Intel Iris: 15:26:50 INFO - Chipset Model: Intel Iris 15:26:50 INFO - Type: GPU 15:26:50 INFO - Bus: Built-In 15:26:50 INFO - VRAM (Dynamic, Max): 1536 MB 15:26:50 INFO - Vendor: Intel (0x8086) 15:26:50 INFO - Device ID: 0x0a2e 15:26:50 INFO - Revision ID: 0x0009 15:26:50 INFO - Displays: 15:26:50 INFO - Display: 15:26:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:26:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:26:50 INFO - Display Serial Number: 1600x1200 60 15:26:50 INFO - Main Display: Yes 15:26:50 INFO - Mirror: Off 15:26:50 INFO - Online: Yes 15:26:50 INFO - Rotation: Supported 15:26:50 INFO - Return code: 0 15:26:50 INFO - Running post-action listener: _resource_record_post_action 15:26:50 INFO - [mozharness: 2016-10-11 22:26:50.869905Z] Finished run-tests step (success) 15:26:50 INFO - Running post-run listener: _resource_record_post_run 15:26:50 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38230528; Write bytes: 709590528; Read time: 250; Write time: 2052 15:26:50 INFO - TinderboxPrint: CPU usage
54.6% 15:26:50 INFO - TinderboxPrint: I/O read bytes / time
38,230,528 / 250 15:26:50 INFO - TinderboxPrint: I/O write bytes / time
709,590,528 / 2,052 15:26:50 INFO - TinderboxPrint: CPU idle
2,202.5 (45.4%) 15:26:50 INFO - TinderboxPrint: CPU system
986.0 (20.3%) 15:26:50 INFO - TinderboxPrint: CPU user
1,665.2 (34.3%) 15:26:50 INFO - TinderboxPrint: Swap in / out
445,591,552 / 0 15:26:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 401621504; Write bytes: 394627072; Read time: 30596; Write time: 831 15:26:50 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 36157952; Write bytes: 298014208; Read time: 230; Write time: 1176 15:26:51 INFO - Running post-run listener: _upload_blobber_files 15:26:51 INFO - Blob upload gear active. 15:26:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:26:51 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:26:51 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:26:51 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:26:51 INFO - (blobuploader) - INFO - Open directory for files ... 15:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:52 INFO - (blobuploader) - INFO - Done attempting. 15:26:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:26:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:26:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:53 INFO - (blobuploader) - INFO - Done attempting. 15:26:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:26:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:26:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:56 INFO - (blobuploader) - INFO - Done attempting. 15:26:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:26:56 INFO - Return code: 0 15:26:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:26:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:26:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/945a74a9206c3925605f3161ceede5b9b41c7789585a1c769735688745408bbddebf927483cb1f2b4acfdd08917f36b80c1228b4adf0ea8c090bec972a15bffb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f128408fac146a9be5e4861fcc5c4e33a845b794d228898965662ca34a7f9758b2a2a9d0e926af6afbde3af5c98c8822bfb15c4a27713ebf9bdfea7e572f45f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/852be17a669376a469656378e4b6281488a47e5b8d7bbb0a907965c98d533e48a6c6033370d56c9c17007d683730be179f304858bebb7bb9bb487657ace3db8a"} 15:26:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:26:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:26:56 INFO - Contents: 15:26:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/945a74a9206c3925605f3161ceede5b9b41c7789585a1c769735688745408bbddebf927483cb1f2b4acfdd08917f36b80c1228b4adf0ea8c090bec972a15bffb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f128408fac146a9be5e4861fcc5c4e33a845b794d228898965662ca34a7f9758b2a2a9d0e926af6afbde3af5c98c8822bfb15c4a27713ebf9bdfea7e572f45f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/852be17a669376a469656378e4b6281488a47e5b8d7bbb0a907965c98d533e48a6c6033370d56c9c17007d683730be179f304858bebb7bb9bb487657ace3db8a"} 15:26:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:26:56 INFO - Copying logs to upload dir... 15:26:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:26:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.956707 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-11 15:26:56.319971) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 15:26:56.323311) ========= 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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/945a74a9206c3925605f3161ceede5b9b41c7789585a1c769735688745408bbddebf927483cb1f2b4acfdd08917f36b80c1228b4adf0ea8c090bec972a15bffb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f128408fac146a9be5e4861fcc5c4e33a845b794d228898965662ca34a7f9758b2a2a9d0e926af6afbde3af5c98c8822bfb15c4a27713ebf9bdfea7e572f45f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/852be17a669376a469656378e4b6281488a47e5b8d7bbb0a907965c98d533e48a6c6033370d56c9c17007d683730be179f304858bebb7bb9bb487657ace3db8a"} build_url:https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010861 build_url: 'https://queue.taskcluster.net/v1/task/G6kgXkztStegcAzn1FwINA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/945a74a9206c3925605f3161ceede5b9b41c7789585a1c769735688745408bbddebf927483cb1f2b4acfdd08917f36b80c1228b4adf0ea8c090bec972a15bffb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f128408fac146a9be5e4861fcc5c4e33a845b794d228898965662ca34a7f9758b2a2a9d0e926af6afbde3af5c98c8822bfb15c4a27713ebf9bdfea7e572f45f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/852be17a669376a469656378e4b6281488a47e5b8d7bbb0a907965c98d533e48a6c6033370d56c9c17007d683730be179f304858bebb7bb9bb487657ace3db8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 15:26:56.355346) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:26:56.355669) ========= 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.P2ZJtl4e6b/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.hty3hZBzgJ/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.004889 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 15:26:56.403773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 15:26:56.404095) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 15:26:56.404503) ========= ========= Total master_lag: 0.17 =========