builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0322 starttime: 1490185312.14 results: success (0) buildid: 20170322030208 builduid: e320fc0ded3f43d7a3c8fbff64fea527 revision: 201231223cd4354a450c3e5d80959f35b8e4cf0c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.140787) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.141199) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.141721) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.169935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.170298) ========= 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.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-22 05:21:52-- 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% 18.5M=0.001s 2017-03-22 05:21:52 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.326864) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.327184) ========= 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.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.026265 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:52.375687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 05:21:52.376066) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 201231223cd4354a450c3e5d80959f35b8e4cf0c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 201231223cd4354a450c3e5d80959f35b8e4cf0c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-22 05:21:52,480 truncating revision to first 12 chars 2017-03-22 05:21:52,480 Setting DEBUG logging. 2017-03-22 05:21:52,481 attempt 1/10 2017-03-22 05:21:52,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/201231223cd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 05:21:53,911 unpacking tar archive at: mozilla-central-201231223cd4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.703729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 05:21:54.103288) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:54.103675) ========= script_repo_revision: 201231223cd4354a450c3e5d80959f35b8e4cf0c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:54.104151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:54.104454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:54.126180) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-22 05:21:54.127009) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:21:54 INFO - MultiFileLogger online at 20170322 05:21:54 in /builds/slave/test 05:21:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:21:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:54 INFO - {'append_to_log': False, 05:21:54 INFO - 'base_work_dir': '/builds/slave/test', 05:21:54 INFO - 'blob_upload_branch': 'Firefox', 05:21:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:54 INFO - 'branch': 'Firefox', 05:21:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:54 INFO - 'config_files': ('talos/mac_config.py',), 05:21:54 INFO - 'default_actions': ('clobber', 05:21:54 INFO - 'read-buildbot-config', 05:21:54 INFO - 'download-and-extract', 05:21:54 INFO - 'populate-webroot', 05:21:54 INFO - 'create-virtualenv', 05:21:54 INFO - 'install', 05:21:54 INFO - 'run-tests'), 05:21:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:54 INFO - 'download_minidump_stackwalk': True, 05:21:54 INFO - 'download_symbols': 'ondemand', 05:21:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:54 INFO - 'gecko_profile': False, 05:21:54 INFO - 'gecko_profile_interval': 0, 05:21:54 INFO - 'installer_path': 'installer.exe', 05:21:54 INFO - 'log_level': 'info', 05:21:54 INFO - 'log_name': 'talos', 05:21:54 INFO - 'log_to_console': True, 05:21:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:21:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:21:54 INFO - 'opt_config_files': (), 05:21:54 INFO - 'pip_index': False, 05:21:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:54 INFO - 'cmd': ('bash', 05:21:54 INFO - '-c', 05:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:54 INFO - 'enabled': True, 05:21:54 INFO - 'halt_on_failure': False, 05:21:54 INFO - 'name': 'check_screen_resolution'},), 05:21:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:54 INFO - 'cmd': ('bash', 05:21:54 INFO - '-c', 05:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:54 INFO - 'enabled': True, 05:21:54 INFO - 'halt_on_failure': False, 05:21:54 INFO - 'name': 'check_screen_resolution'},), 05:21:54 INFO - 'run_cmd_checks_enabled': True, 05:21:54 INFO - 'suite': 'dromaeojs-e10s', 05:21:54 INFO - 'system_bits': '32', 05:21:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:54 INFO - 'title': 't-yosemite-r7-0322', 05:21:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:54 INFO - 'use_talos_json': True, 05:21:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:54 INFO - 'work_dir': 'build'} 05:21:54 INFO - [mozharness: 2017-03-22 12:21:54.334305Z] Running clobber step. 05:21:54 INFO - Running pre-action listener: _resource_record_pre_action 05:21:54 INFO - Running main action method: clobber 05:21:54 INFO - rmtree: /builds/slave/test/build 05:21:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:56 INFO - Running post-action listener: _resource_record_post_action 05:21:56 INFO - [mozharness: 2017-03-22 12:21:56.660939Z] Finished clobber step (success) 05:21:56 INFO - [mozharness: 2017-03-22 12:21:56.661085Z] Running read-buildbot-config step. 05:21:56 INFO - Running pre-action listener: _resource_record_pre_action 05:21:56 INFO - Running main action method: read_buildbot_config 05:21:56 INFO - Using buildbot properties: 05:21:56 INFO - { 05:21:56 INFO - "project": "", 05:21:56 INFO - "product": "firefox", 05:21:56 INFO - "script_repo_revision": "production", 05:21:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:21:56 INFO - "repository": "", 05:21:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:21:56 INFO - "buildid": "20170322030208", 05:21:56 INFO - "stage_platform": "macosx64", 05:21:56 INFO - "basedir": "/builds/slave/test", 05:21:56 INFO - "nightly_build": "True", 05:21:56 INFO - "buildnumber": 131, 05:21:56 INFO - "slavename": "t-yosemite-r7-0322", 05:21:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:21:56 INFO - "platform": "yosemite_r7", 05:21:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:21:56 INFO - "branch": "mozilla-central", 05:21:56 INFO - "repo_path": "mozilla-central", 05:21:56 INFO - "revision": "201231223cd4354a450c3e5d80959f35b8e4cf0c", 05:21:56 INFO - "pgo_build": "False", 05:21:56 INFO - "builduid": "e320fc0ded3f43d7a3c8fbff64fea527", 05:21:56 INFO - "slavebuilddir": "test" 05:21:56 INFO - } 05:21:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:21:56 INFO - Running post-action listener: _resource_record_post_action 05:21:56 INFO - [mozharness: 2017-03-22 12:21:56.665461Z] Finished read-buildbot-config step (success) 05:21:56 INFO - [mozharness: 2017-03-22 12:21:56.665600Z] Running download-and-extract step. 05:21:56 INFO - Running pre-action listener: _resource_record_pre_action 05:21:56 INFO - Running main action method: download_and_extract 05:21:56 INFO - mkdir: /builds/slave/test/build/tests 05:21:56 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')]} 05:21:56 INFO - trying https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:21:56 INFO - Downloading https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:21:57 INFO - Downloaded 1325 bytes. 05:21:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:21:57 INFO - Using the following test package requirements: 05:21:57 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:21:57 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:21:57 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:21:57 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:21:57 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'jsshell-mac.zip'], 05:21:57 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:21:57 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:21:57 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:21:57 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:21:57 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:21:57 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:21:57 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:21:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:21:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:21:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:21:57 INFO - Fetch https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:21:59 INFO - Expected file size: 52474935 05:21:59 INFO - Obtained file size: 52474935 05:22:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:22:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:22:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:22:15 INFO - Fetch https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:22:17 INFO - Expected file size: 52474935 05:22:17 INFO - Obtained file size: 52474935 05:22:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:22:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:22:32 INFO - Fetch https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:22:34 INFO - Expected file size: 14404171 05:22:34 INFO - Obtained file size: 14404171 05:22:34 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')]} 05:22:34 INFO - trying https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:22:34 INFO - Downloading https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:22:36 INFO - Downloaded 75354366 bytes. 05:22:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:22:36 INFO - mkdir: /builds/slave/test/properties 05:22:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:22:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:22:36 INFO - Contents: 05:22:36 INFO - build_url:https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:22:37 INFO - Running post-action listener: _resource_record_post_action 05:22:37 INFO - Running post-action listener: set_extra_try_arguments 05:22:37 INFO - [mozharness: 2017-03-22 12:22:37.415336Z] Finished download-and-extract step (success) 05:22:37 INFO - [mozharness: 2017-03-22 12:22:37.415476Z] Running populate-webroot step. 05:22:37 INFO - Running pre-action listener: _resource_record_pre_action 05:22:37 INFO - Running main action method: populate_webroot 05:22:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:37 INFO - '--tppagecycles', 05:22:37 INFO - '7'], 05:22:37 INFO - 'tests': ['tsvgm']}}, 05:22:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:22:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:22:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:22:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['damp', 'tps']}, 05:22:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'tests': ['damp', 'tps']}, 05:22:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['dromaeo_dom']}, 05:22:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['basic_compositor_video']}, 05:22:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:22:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'other-e10s': {'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['a11yr', 05:22:37 INFO - 'ts_paint', 05:22:37 INFO - 'tpaint', 05:22:37 INFO - 'sessionrestore', 05:22:37 INFO - 'sessionrestore_no_auto_restore', 05:22:37 INFO - 'tabpaint']}, 05:22:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['tsvgx', 05:22:37 INFO - 'tsvgr_opacity', 05:22:37 INFO - 'tart', 05:22:37 INFO - 'tscrollx', 05:22:37 INFO - 'cart', 05:22:37 INFO - 'tsvg_static']}, 05:22:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:22:37 INFO - 'tsvgr_opacity', 05:22:37 INFO - 'tart', 05:22:37 INFO - 'tscrollx', 05:22:37 INFO - 'cart', 05:22:37 INFO - 'tsvg_static']}, 05:22:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'talos_options': ['--disable-e10s'], 05:22:37 INFO - 'tests': ['tp5o']}, 05:22:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:22:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'talos_options': ['--disable-e10s', 05:22:37 INFO - '--xperf_path', 05:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:37 INFO - 'tests': ['tp5n']}, 05:22:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:22:37 INFO - 'talos_options': ['--xperf_path', 05:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:37 INFO - 'tests': ['tp5n']}}, 05:22:37 INFO - 'talos.zip': {'path': '', 05:22:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:37 INFO - Running post-action listener: _resource_record_post_action 05:22:37 INFO - [mozharness: 2017-03-22 12:22:37.434264Z] Finished populate-webroot step (success) 05:22:37 INFO - [mozharness: 2017-03-22 12:22:37.434341Z] Running create-virtualenv step. 05:22:37 INFO - Running pre-action listener: _resource_record_pre_action 05:22:37 INFO - Running main action method: create_virtualenv 05:22:37 INFO - Creating virtualenv /builds/slave/test/build/venv 05:22:37 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 05:22:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:22:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:37 INFO - Using real prefix '/tools/python27' 05:22:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:41 INFO - Installing pip.................done. 05:22:41 INFO - Return code: 0 05:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:22:41 INFO - Reading from file tmpfile_stdout 05:22:41 INFO - Output received: 05:22:41 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) 05:22:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:41 INFO - 'HOME': '/Users/cltbld', 05:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:41 INFO - 'LOGNAME': 'cltbld', 05:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:41 INFO - 'MOZ_NO_REMOTE': '1', 05:22:41 INFO - 'NO_EM_RESTART': '1', 05:22:41 INFO - 'PAGER': '/bin/cat', 05:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:41 INFO - 'PWD': '/builds/slave/test', 05:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:41 INFO - 'SHELL': '/bin/bash', 05:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:41 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:41 INFO - 'USER': 'cltbld', 05:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:41 INFO - 'XPC_FLAGS': '0x0', 05:22:41 INFO - 'XPC_SERVICE_NAME': '0', 05:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:41 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 05:22:41 INFO - Cleaning up... 05:22:41 INFO - Return code: 0 05:22:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:41 INFO - 'HOME': '/Users/cltbld', 05:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:41 INFO - 'LOGNAME': 'cltbld', 05:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:41 INFO - 'MOZ_NO_REMOTE': '1', 05:22:41 INFO - 'NO_EM_RESTART': '1', 05:22:41 INFO - 'PAGER': '/bin/cat', 05:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:41 INFO - 'PWD': '/builds/slave/test', 05:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:41 INFO - 'SHELL': '/bin/bash', 05:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:41 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:41 INFO - 'USER': 'cltbld', 05:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:41 INFO - 'XPC_FLAGS': '0x0', 05:22:41 INFO - 'XPC_SERVICE_NAME': '0', 05:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:41 INFO - Downloading/unpacking psutil>=3.1.1 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:45 INFO - Installing collected packages: psutil 05:22:45 INFO - Running setup.py install for psutil 05:22:45 INFO - building 'psutil._psutil_osx' extension 05:22:45 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 05:22:46 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 05:22:46 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 05:22:46 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 05:22:46 INFO - building 'psutil._psutil_posix' extension 05:22:46 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 05:22:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:22:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:22:46 INFO - ^ 05:22:46 INFO - 1 warning generated. 05:22:46 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 05:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:46 INFO - Successfully installed psutil 05:22:46 INFO - Cleaning up... 05:22:46 INFO - Return code: 0 05:22:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:46 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:46 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 05:22:46 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 05:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:46 INFO - 'HOME': '/Users/cltbld', 05:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:46 INFO - 'LOGNAME': 'cltbld', 05:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:46 INFO - 'MOZ_NO_REMOTE': '1', 05:22:46 INFO - 'NO_EM_RESTART': '1', 05:22:46 INFO - 'PAGER': '/bin/cat', 05:22:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:46 INFO - 'PWD': '/builds/slave/test', 05:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:46 INFO - 'SHELL': '/bin/bash', 05:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:46 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:46 INFO - 'USER': 'cltbld', 05:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:46 INFO - 'XPC_FLAGS': '0x0', 05:22:46 INFO - 'XPC_SERVICE_NAME': '0', 05:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:22:49 INFO - Installing collected packages: mozsystemmonitor 05:22:49 INFO - Running setup.py install for mozsystemmonitor 05:22:49 INFO - Successfully installed mozsystemmonitor 05:22:49 INFO - Cleaning up... 05:22:50 INFO - Return code: 0 05:22:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:22:50 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')]} 05:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:50 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')]} 05:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:50 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:50 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 05:22:50 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 05:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:50 INFO - 'HOME': '/Users/cltbld', 05:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:50 INFO - 'LOGNAME': 'cltbld', 05:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:50 INFO - 'MOZ_NO_REMOTE': '1', 05:22:50 INFO - 'NO_EM_RESTART': '1', 05:22:50 INFO - 'PAGER': '/bin/cat', 05:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:50 INFO - 'PWD': '/builds/slave/test', 05:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:50 INFO - 'SHELL': '/bin/bash', 05:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:50 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:50 INFO - 'USER': 'cltbld', 05:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:50 INFO - 'XPC_FLAGS': '0x0', 05:22:50 INFO - 'XPC_SERVICE_NAME': '0', 05:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:50 INFO - Downloading/unpacking jsonschema==2.5.1 05:22:50 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 05:22:50 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 05:22:50 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 05:22:50 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 05:22:50 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 05:22:50 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 05:22:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:22:53 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 05:22:53 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 05:22:53 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 05:22:53 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 05:22:53 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 05:22:53 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 05:22:54 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:54 INFO - warning: no files found matching '*.txt' 05:22:54 INFO - no previously-included directories found matching 'build' 05:22:54 INFO - no previously-included directories found matching 'dist' 05:22:54 INFO - no previously-included directories found matching '.git*' 05:22:54 INFO - Installing collected packages: jsonschema, functools32 05:22:54 INFO - Running setup.py install for functools32 05:22:54 INFO - warning: no files found matching '*.txt' 05:22:54 INFO - no previously-included directories found matching 'build' 05:22:54 INFO - no previously-included directories found matching 'dist' 05:22:54 INFO - no previously-included directories found matching '.git*' 05:22:54 INFO - Successfully installed jsonschema functools32 05:22:54 INFO - Cleaning up... 05:22:54 INFO - Return code: 0 05:22:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:54 INFO - 'HOME': '/Users/cltbld', 05:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:54 INFO - 'LOGNAME': 'cltbld', 05:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:54 INFO - 'MOZ_NO_REMOTE': '1', 05:22:54 INFO - 'NO_EM_RESTART': '1', 05:22:54 INFO - 'PAGER': '/bin/cat', 05:22:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:54 INFO - 'PWD': '/builds/slave/test', 05:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:54 INFO - 'SHELL': '/bin/bash', 05:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:54 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:54 INFO - 'USER': 'cltbld', 05:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:54 INFO - 'XPC_FLAGS': '0x0', 05:22:54 INFO - 'XPC_SERVICE_NAME': '0', 05:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:22:54 INFO - Cleaning up... 05:22:55 INFO - Return code: 0 05:22:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:55 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')]} 05:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:55 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')]} 05:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:55 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:22:55 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 05:22:55 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 05:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:55 INFO - 'HOME': '/Users/cltbld', 05:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:55 INFO - 'LOGNAME': 'cltbld', 05:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:55 INFO - 'MOZ_NO_REMOTE': '1', 05:22:55 INFO - 'NO_EM_RESTART': '1', 05:22:55 INFO - 'PAGER': '/bin/cat', 05:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:55 INFO - 'PWD': '/builds/slave/test', 05:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:55 INFO - 'SHELL': '/bin/bash', 05:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:22:55 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:55 INFO - 'USER': 'cltbld', 05:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:55 INFO - 'XPC_FLAGS': '0x0', 05:22:55 INFO - 'XPC_SERVICE_NAME': '0', 05:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:55 INFO - Downloading/unpacking blobuploader==1.2.4 05:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:57 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:58 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 05:22:59 INFO - Downloading docopt-0.6.1.tar.gz 05:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:59 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:59 INFO - Running setup.py install for blobuploader 05:22:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:59 INFO - Running setup.py install for requests 05:22:59 INFO - Running setup.py install for docopt 05:22:59 INFO - Successfully installed blobuploader requests docopt 05:22:59 INFO - Cleaning up... 05:23:00 INFO - Return code: 0 05:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:00 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:23:00 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 05:23:00 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 05:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:00 INFO - 'HOME': '/Users/cltbld', 05:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:00 INFO - 'LOGNAME': 'cltbld', 05:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:00 INFO - 'MOZ_NO_REMOTE': '1', 05:23:00 INFO - 'NO_EM_RESTART': '1', 05:23:00 INFO - 'PAGER': '/bin/cat', 05:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:00 INFO - 'PWD': '/builds/slave/test', 05:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:00 INFO - 'SHELL': '/bin/bash', 05:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:23:00 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:00 INFO - 'USER': 'cltbld', 05:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:00 INFO - 'XPC_FLAGS': '0x0', 05:23:00 INFO - 'XPC_SERVICE_NAME': '0', 05:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-R7HLrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-291GGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-pFJjex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-AFqyyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Jb9scP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-nM_ry7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:00 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-R3HEl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-FUFSIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-WT7CkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-J3kCVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-W5zEN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-2aA9BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-j9Oumh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-9QIzTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-3nbD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:01 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-SaHi4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:02 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-iQhWHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:02 INFO - Running setup.py install for manifestparser 05:23:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:02 INFO - Running setup.py install for mozcrash 05:23:02 INFO - Running setup.py install for mozdebug 05:23:02 INFO - Running setup.py install for mozdevice 05:23:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:02 INFO - Running setup.py install for mozfile 05:23:02 INFO - Running setup.py install for mozhttpd 05:23:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:02 INFO - Running setup.py install for mozinfo 05:23:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Running setup.py install for mozInstall 05:23:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Running setup.py install for mozleak 05:23:03 INFO - Running setup.py install for mozlog 05:23:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Running setup.py install for moznetwork 05:23:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:03 INFO - Running setup.py install for mozprocess 05:23:03 INFO - Running setup.py install for mozprofile 05:23:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:04 INFO - Running setup.py install for mozrunner 05:23:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:04 INFO - Running setup.py install for mozscreenshot 05:23:04 INFO - Running setup.py install for moztest 05:23:04 INFO - Running setup.py install for mozversion 05:23:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:04 INFO - Cleaning up... 05:23:04 INFO - Return code: 0 05:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:23:04 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 05:23:04 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 05:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:04 INFO - 'HOME': '/Users/cltbld', 05:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:04 INFO - 'LOGNAME': 'cltbld', 05:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:04 INFO - 'MOZ_NO_REMOTE': '1', 05:23:04 INFO - 'NO_EM_RESTART': '1', 05:23:04 INFO - 'PAGER': '/bin/cat', 05:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:04 INFO - 'PWD': '/builds/slave/test', 05:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:04 INFO - 'SHELL': '/bin/bash', 05:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:23:04 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:04 INFO - 'USER': 'cltbld', 05:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:04 INFO - 'XPC_FLAGS': '0x0', 05:23:04 INFO - 'XPC_SERVICE_NAME': '0', 05:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:04 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OJ39va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-PM5i7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-4YJcOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Esjl46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-z8L5qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-2wi_qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-yM7HDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-marFYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-j8LKVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:05 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)) 05:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:05 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-NZqpuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:06 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)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-EmCufV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:06 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)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-uOh1kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-R5NdwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:06 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)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-zEtydQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-fTQjBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:06 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)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-acukRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:06 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-bFCXQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:06 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)) 05:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:06 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)) 05:23:06 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:06 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:09 INFO - Downloading blessings-1.6.tar.gz 05:23:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:09 INFO - Installing collected packages: blessings 05:23:09 INFO - Running setup.py install for blessings 05:23:09 INFO - Successfully installed blessings 05:23:09 INFO - Cleaning up... 05:23:09 INFO - Return code: 0 05:23:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:09 INFO - Reading from file tmpfile_stdout 05:23:09 INFO - Current package versions: 05:23:09 INFO - blessings == 1.6 05:23:09 INFO - blobuploader == 1.2.4 05:23:09 INFO - docopt == 0.6.1 05:23:09 INFO - functools32 == 3.2.3-2 05:23:09 INFO - jsonschema == 2.5.1 05:23:09 INFO - manifestparser == 1.1 05:23:09 INFO - mozInstall == 1.12 05:23:09 INFO - mozcrash == 1.0 05:23:09 INFO - mozdebug == 0.1 05:23:09 INFO - mozdevice == 0.49 05:23:09 INFO - mozfile == 1.2 05:23:09 INFO - mozhttpd == 0.7 05:23:09 INFO - mozinfo == 0.9 05:23:09 INFO - mozleak == 0.1 05:23:09 INFO - mozlog == 3.4 05:23:09 INFO - moznetwork == 0.27 05:23:09 INFO - mozprocess == 0.25 05:23:09 INFO - mozprofile == 0.28 05:23:09 INFO - mozrunner == 6.13 05:23:09 INFO - mozscreenshot == 0.1 05:23:09 INFO - mozsystemmonitor == 0.3 05:23:09 INFO - moztest == 0.8 05:23:09 INFO - mozversion == 1.4 05:23:09 INFO - psutil == 3.1.1 05:23:09 INFO - requests == 1.2.3 05:23:09 INFO - wsgiref == 0.1.2 05:23:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:09 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')]} 05:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:09 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')]} 05:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:09 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:23:09 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 05:23:09 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 05:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:09 INFO - 'HOME': '/Users/cltbld', 05:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:09 INFO - 'LOGNAME': 'cltbld', 05:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:09 INFO - 'MOZ_NO_REMOTE': '1', 05:23:09 INFO - 'NO_EM_RESTART': '1', 05:23:09 INFO - 'PAGER': '/bin/cat', 05:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:09 INFO - 'PWD': '/builds/slave/test', 05:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:09 INFO - 'SHELL': '/bin/bash', 05:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:23:09 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:09 INFO - 'USER': 'cltbld', 05:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:09 INFO - 'XPC_FLAGS': '0x0', 05:23:09 INFO - 'XPC_SERVICE_NAME': '0', 05:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 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)) 05:23:10 INFO - Cleaning up... 05:23:10 INFO - Return code: 0 05:23:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23:10 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')]} 05:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:10 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')]} 05:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:10 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 0x1041afb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041b2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf4149fef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, '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.rZHkFCibSl/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.cPADND4paH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 05:23:10 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 05:23:10 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 05:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:10 INFO - 'HOME': '/Users/cltbld', 05:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:10 INFO - 'LOGNAME': 'cltbld', 05:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:10 INFO - 'MOZ_NO_REMOTE': '1', 05:23:10 INFO - 'NO_EM_RESTART': '1', 05:23:10 INFO - 'PAGER': '/bin/cat', 05:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:10 INFO - 'PWD': '/builds/slave/test', 05:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:10 INFO - 'SHELL': '/bin/bash', 05:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:23:10 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:10 INFO - 'USER': 'cltbld', 05:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:10 INFO - 'XPC_FLAGS': '0x0', 05:23:10 INFO - 'XPC_SERVICE_NAME': '0', 05:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:10 INFO - Cleaning up... 05:23:10 INFO - Return code: 0 05:23:10 INFO - Running post-action listener: _resource_record_post_action 05:23:10 INFO - Running post-action listener: _start_resource_monitoring 05:23:10 INFO - Starting resource monitoring. 05:23:10 INFO - [mozharness: 2017-03-22 12:23:10.548715Z] Finished create-virtualenv step (success) 05:23:10 INFO - [mozharness: 2017-03-22 12:23:10.549684Z] Running install step. 05:23:10 INFO - Running pre-action listener: _resource_record_pre_action 05:23:10 INFO - Running main action method: install 05:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:10 INFO - Reading from file tmpfile_stdout 05:23:10 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:10 INFO - Reading from file tmpfile_stdout 05:23:10 INFO - Output received: 05:23:10 INFO - Usage: mozinstall [options] installer 05:23:10 INFO - Options: 05:23:10 INFO - -h, --help show this help message and exit 05:23:10 INFO - -d DEST, --destination=DEST 05:23:10 INFO - Directory to install application into. [default: 05:23:10 INFO - "/builds/slave/test"] 05:23:10 INFO - --app=APP Application being installed. [default: firefox] 05:23:10 INFO - mkdir: /builds/slave/test/build/application 05:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:23:30 INFO - Reading from file tmpfile_stdout 05:23:30 INFO - Output received: 05:23:30 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:23:30 INFO - Running post-action listener: _resource_record_post_action 05:23:30 INFO - [mozharness: 2017-03-22 12:23:30.991249Z] Finished install step (success) 05:23:30 INFO - [mozharness: 2017-03-22 12:23:30.991403Z] Running run-tests step. 05:23:30 INFO - Running pre-action listener: _resource_record_pre_action 05:23:30 INFO - Running main action method: run_tests 05:23:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:31 INFO - 2017-03-22 05:23:31.005 screenresolution[1622:7308] starting screenresolution argv=screenresolution get 05:23:31 INFO - 2017-03-22 05:23:31.008 screenresolution[1622:7308] Display 0: 1600x1200x32@60 05:23:31 INFO - 2017-03-22 05:23:31.014 screenresolution[1623:7310] starting screenresolution argv=screenresolution list 05:23:31 INFO - Available Modes on Display 0 05:23:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:31 INFO - Intel Iris: 05:23:31 INFO - Chipset Model: Intel Iris 05:23:31 INFO - Type: GPU 05:23:31 INFO - Bus: Built-In 05:23:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:31 INFO - Vendor: Intel (0x8086) 05:23:31 INFO - Device ID: 0x0a2e 05:23:31 INFO - Revision ID: 0x0009 05:23:31 INFO - Displays: 05:23:31 INFO - Display: 05:23:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:31 INFO - Display Serial Number: 1600x1200 60 05:23:31 INFO - Main Display: Yes 05:23:31 INFO - Mirror: Off 05:23:31 INFO - Online: Yes 05:23:31 INFO - Rotation: Supported 05:23:31 INFO - Return code: 0 05:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:23:31 INFO - Python 2.7.3 05:23:31 INFO - Return code: 0 05:23:31 INFO - grabbing minidump binary from tooltool 05:23:31 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')]} 05:23:31 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 0x103f8dd30>, '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 0x103eb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f82be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:23:31 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 05:23:31 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 05:23:31 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 05:23:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:23:31 INFO - Return code: 0 05:23:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:23:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:23:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:23:31 INFO - ENV: RUST_BACKTRACE is now 1 05:23:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:23:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 05:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0322 --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 05:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rZHkFCibSl/Render', 05:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:31 INFO - 'HOME': '/Users/cltbld', 05:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:31 INFO - 'LOGNAME': 'cltbld', 05:23:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:23:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:31 INFO - 'MOZ_NO_REMOTE': '1', 05:23:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:23:31 INFO - 'NO_EM_RESTART': '1', 05:23:31 INFO - 'PAGER': '/bin/cat', 05:23:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:31 INFO - 'PWD': '/builds/slave/test', 05:23:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:31 INFO - 'RUST_BACKTRACE': '1', 05:23:31 INFO - 'SHELL': '/bin/bash', 05:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPADND4paH/Listeners', 05:23:31 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:31 INFO - 'USER': 'cltbld', 05:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:31 INFO - 'XPC_FLAGS': '0x0', 05:23:31 INFO - 'XPC_SERVICE_NAME': '0', 05:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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 05:23:31 INFO - mozversion application_buildid: 20170322030208 05:23:31 INFO - mozversion application_changeset: 201231223cd4354a450c3e5d80959f35b8e4cf0c 05:23:31 INFO - mozversion application_display_name: Nightly 05:23:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:23:31 INFO - mozversion application_name: Firefox 05:23:31 INFO - mozversion application_remotingname: firefox 05:23:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:23:31 INFO - mozversion application_vendor: Mozilla 05:23:31 INFO - mozversion application_version: 55.0a1 05:23:31 INFO - mozversion platform_buildid: 20170322030208 05:23:31 INFO - mozversion platform_changeset: 201231223cd4354a450c3e5d80959f35b8e4cf0c 05:23:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:23:31 INFO - mozversion platform_version: 55.0a1 05:23:31 INFO - using testdate: 1490185411 05:23:31 INFO - actual date: 1490185411 05:23:31 INFO - starting webserver on 'localhost:49242' 05:23:31 INFO - SUITE-START | Running 2 tests 05:23:31 INFO - TEST-START | dromaeo_css 05:23:31 INFO - operating with platform_type : mac_ 05:23:31 INFO - Initialising browser for dromaeo_css test... 05:23:31 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpg4uLOo/profile http://localhost:49242/getInfo.html) 05:23:35 INFO - PID 1633 | 2017-03-22 05:23:35.024 plugin-container[1636:7494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 05:23:35 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:35 INFO - PID 1633 | 2017-03-22 05:23:35.025 plugin-container[1636:7494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 05:23:35 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:35 INFO - PID 1633 | 2017-03-22 05:23:35.025 plugin-container[1636:7494] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:35 INFO - PID 1633 | __metrics Screen width/height:1600/1200 05:23:35 INFO - PID 1633 | colorDepth:24 05:23:35 INFO - PID 1633 | Browser inner width/height: 1024/689 05:23:35 INFO - PID 1633 | __metrics 05:23:35 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:23:35 INFO - TEST-INFO | 1633: exit 0 05:23:35 INFO - Browser initialized. 05:23:35 INFO - Running cycle 1/1 for dromaeo_css test... 05:23:35 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpg4uLOo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:38 INFO - PID 1637 | 2017-03-22 05:23:38.543 plugin-container[1638:7628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 05:23:38 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:38 INFO - PID 1637 | 2017-03-22 05:23:38.543 plugin-container[1638:7628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 05:23:38 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:38 INFO - PID 1637 | 2017-03-22 05:23:38.543 plugin-container[1638:7628] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:48 INFO - PID 1637 | RSS: Main: 227618816 05:23:48 INFO - PID 1637 | 05:26:53 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 05:26:54 INFO - PID 1637 | RSS: Main: 250605568 05:26:54 INFO - PID 1637 | 05:28:36 INFO - PID 1637 | ************************* 05:28:36 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:28:36 INFO - PID 1637 | 05:28:36 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:28:36 INFO - PID 1637 | Full stack: 05:28:36 INFO - PID 1637 | ************************* 05:29:57 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 05:29:57 INFO - PID 1637 | RSS: Main: 259043328 05:29:57 INFO - PID 1637 | 05:32:59 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 05:33:00 INFO - PID 1637 | RSS: Main: 270499840 05:33:00 INFO - PID 1637 | 05:36:02 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 05:36:02 INFO - PID 1637 | RSS: Main: 263700480 05:36:02 INFO - PID 1637 | 05:39:05 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 05:39:06 INFO - PID 1637 | RSS: Main: 274436096 05:39:06 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 05:42:09 INFO - PID 1637 | RSS: Main: 268369920 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | __start_tp_report 05:42:09 INFO - PID 1637 | _x_x_mozilla_page_load 05:42:09 INFO - PID 1637 | _x_x_mozilla_page_load_details 05:42:09 INFO - PID 1637 | |i|pagename|runs| 05:42:09 INFO - PID 1637 | |0;dojo.html;2887.2060183449557;3502.334556434276;3537.1510837399014;3543.3444812709668;3597.2086141048967;5008.424031236409;5830.212921255629;6063.666498342589;6164.167837341957;6209.844753881155;5033.622478314122;5975.834712231117;6104.0347610180015;6113;6132.1721567588365;5496.0107180707555;5579.581531385142;5582.748776305076;5696.601237913348;5725.14122881569;4734.739589322586;5463.754131064092;5654.293213348332;5663.008973429633;5668.149777533362;5538.501534861862;5679.977604031273;5731.942680573183;5759.856003599902;5784.942150578482;5329.120695085307;5631.831045068655;5660.3490598581275;5723.284589426322;5781.479666829984;4440.666949978732;5373.247745315659;5417.810376636839;5497.175423686479;5589.636673616202;6055.697215139226;6112.266528016622;6118.938810611881;6148.754049838001;6180.721867515973;6600.306967768412;6620.006998950148;6639.070530125786;6640.236372817139;6668.399844014013;5574.721263936787;6429.582077165016;6520.641364724938;6619.669016549202;6670.6331151786635;6248.31268560458;6264.248290205159;6265.310815810257;6271.592346497554;6284.994400895926;6706.865862682816;6768.289329620417;6838.076859623887;6859.2454829968665;6868.278830722802;6261.96869015652;6343.0485427186295;6453.354664533509;6484.675766211671;6520.347965203442;6666.799996000128;6706.161729783777;6725.361090696376;6739.157605299338;6741.966290168616;6541.378569035934;6580.144581204413;6592.9340706593275;6602.504812139109;6639.867202656016;5314.468553144732;6377.234731832117;6460.903086453707;6571.80284591463;6663.800085997428;5292.867678308073;5309.150535914235;5312.203169524602;5329.786808527693;5351.357837059578;4700.670953033323;4739.146953548395;4746.240601503743;4771.308160316735;4776.785044672998;6108.493847774667;6204.007358822657;6290.905636415458;6296.212973378328;6322.430981211621;6679.56582822115;6685.832854178587;6703.798886033427;6728.192616886005;6738.831529211809;6280.246370435577;6285.779997700059;6291.716872740738;6303.716332765037;6306.054091886162;5706.286714160813;5917.52659787216;5919.437653422918;5926.2888453385;5928.436798504135;5723.3418156912485;6083.178770865877;6715.899261511179;6840.452763778786;6950.304969503211;6522.34776522344;6665.466762658879;6743.662816859235;6746.763863264762;6771.796846094625;6144.003198960267;6326.335734747785;6680.0647909291765;6910.689018994057;6915.550489218184;4936.75316234194;5453.945460545344;6359.8092057238355;6564.98242772371;6636.369544893228;6481.675916204076;6584.572002819801;6655.467562594884;6670.499712521678;6738.59568425896;5924.585279030426;6171.56799024064;6500.447461965786;6544.083828263952;6563.573367731082;5904.940950590611;5924.815036992705;5973.671448070398;6510.609363438209;6542.640154791532;5048.3941926969;5166.3800343958965;5379.757910893941;6295.276043254901;6331.5251356147555;6526.869462610816;6557.606543607399;6565.868682626226;6567.2447668518735;6578.079068930258;4848.369711937426;6324.873502530016;6335.239771227482;6380.298167201697;6393.552451328363 05:42:09 INFO - PID 1637 | |1;ext.html;695.3811108113778;751.8045308219861;797.3899966525607;1259.8236246925433;1266.556705153197;36853.07867303315;37738;37756.244875102515;37775.244495110084;37825.43261851074;38288.617113828856;38550.61449385505;38595.22809543808;38668.61331386685;38707;7931.8413631727335;8107.945967024279;8362.414630975834;8598.355123365742;8598.527081010541;8556.78608034801;8655.95672021639;8665.17680820321;8669.349798765117;8671.826563468727;8615.35384846136;8685.435446695976;8686.826263474726;8693.739187824376;8706.869396959051;8629.568521573927;8667.696630617898;8670.176333248362;8683.869741953877;8703.086175951528;8529.23236908675;8532.957335213347;8594.441361311494;8603.440776349516;8640.135986401372;37502;37509.62490375089;37703;37714.43428348577;37768.62231377679;3036.2917714696227;3040.7567394608377;3054.5570892220726;3063.479208534532;3067.5245336972803;27328.726712732816;27561.44877102467;27658.723412765816;27721;28400.721967511512;2800.579913013044;2816.197383745643;2885.365219651694;2898.8260704357594;2931.6775154732964;233766;239711;278476;349359;350233;45897.08205835865;46073;46080.5391946083;46440.83897902512;46543;36258.63741362606;48305.03389932182;48773.75613121912;48964.51035489671;49030.75484622626;11544.480498377594;11845.933865952105;14224.786628200585;14234.359453824603;14282.57152285433;11636.709082273012;11716;11782.52869885195;11785.646430607096;11787.939085482347;13754;13777.655558611115;13807;13820.447182112603;13840.861591383957;6029.698515074228;6057.757689692451;6079.908801367983;6082.171790117616;6098.26820781509;878.9340799440067;896.950667713269;922.1700469577434;977.1205914676848;1038.4963292802984;23672.64491032636;23733.881330593238;24011.879940600185;24053;24224.87887560586;1077.7305673581604;1089.6730980705756;1108.9500972456258;1128.9887101129048;1140.2132528555271;11053.889461105286;11703.473343699554;12260.632181034583;12262.938685306517;12723.982081433649;436762;438643;439129;439317;439430.8028459837;53965.1905221422;54215.45784542183;54329.185062224096;54353.1847022295;54387.72806135944;1471.492335144395;1482;1512.138081293652;1523.916184609857;1524.7712843073627;19718.704219436433;23482.412939676644;23658.88170559136;23677.881610591838;23770.643440348755;37387.06532336713;37558.43662345012;37603;37613.05967350838;37648.811755941046;357686;359397;359696;361458;361867;30751.692483074883;31508.212294692818;31580.526292106097;31644.208894777814;32189.839050804596;35156.29687406187;37930.86207413782;39325;40574.59425405826;40611;27162;33474.82838100655;33542;34260.314793704485;34414.48378274378;33444.163895901824;33809.49285760667;34361.140971475914;34545;35564.466533002545;34736;34977.475337869444;35503.75736849198;35632.28735425329;35748.106297342776;29813.5527967085;31347;31351.90268340597;31477.213069673442;31547.684523154476;22017.899105044362;23592;23633.29100126999;24054.87972560126;24238.63642045406 05:42:09 INFO - PID 1637 | |2;jquery.html;378.9810509474529;392.7977091797725;429.4181384224376;429.5918877066786;450.7678545549041;18794.718079228824;19088.90455547724;19671.704924426143;19738.210471581155;20730.27444039459;22087.889560552176;22096.88951555244;22123.446913827123;23228.41928951777;23467.76532234677;5749.425057494249;6250.499960003189;6307.180066591337;6308.653024083674;6315.557910946236;5355.848492574096;5920.822375328747;6093.482054025413;6154.907676384858;6264.718087686042;6163.691815409212;6184.134221209034;6194.752209911598;6201.069839524062;6202.96898515509;5702;5996.520278377741;6123.663198524079;6209.565330426872;6210.9068363974575;5257.921131183035;5534.225208470817;5537.363203231599;5541.639793413456;5548.4174161713245;27170.45659086807;27311.863440682868;27965.300867478272;28109.85945070282;28262.293442663893;2021.7674967378646;2024.6254442928105;2057.423921302023;2089.39407571804;2090.6654935210295;15356.769648455282;16059.277332520065;16156;16325.918370408192;17400.65198696019;1424.886009119268;1645.6215070533724;1701.8553422959064;1707.5560354307845;1737.2529812180758;114806.42596786963;139523;150464.2476787609;152866.7069993951;153626;10295.227857910686;11581.478833452515;11690.181687281978;11933.105017123751;12038.759224815454;16515.421960231335;16779.664406711796;16814.91592542054;16842.747358789627;16931.830681693027;8884.600192991331;8910;9011.414258073204;9109.453432794053;9112.726618201465;8358.916410835815;8392.622331995075;8433.493990360597;8585.441946273471;8585.957070214734;6814.795556133324;7777.261160189773;8024.317932975646;8119.3910456715985;8140.75577732669;2105.2526353144717;2148.237375731624;2231.6875637410776;2245.3376254005107;2436.6223235398543;794.9364050875917;907.2651152566442;909.8180363927241;954.160338901762;964.7781010367655;20198.39404817858;20878.79121208799;20900.895495522425;20950.266740664003;21163.484486873385;911.7766147293955;946.6024269806697;954.5895265036102;974.3082411487782;976.8095221431753;10118.848217276747;10782.622608208674;10784.015176097677;10812.243142980073;10882.129429645609;580470;581193;589778.4821200902;688410;704840;97688.53467197997;116581.417092914;120920;125370.37314813367;128765;1017.6031347774374;1125.6454216921643;1245.8255844181822;1255.1465003797505;1256.7800634888858;21180.258690945742;21225.36323910285;21298.467538311666;21532.784672153706;21603.891980539996;49671.01973881434;54033.18950215828;58558.77926713901;61676.69161654163;62061;74328;75968.62015689886;80871.38257234705;89423.10576894408;90420.5478972601;8551.914480855112;8585.18440748128;8630.309575233841;8925.152110549487;8989.550522473719;13252.801207981694;13464.394102265225;13747.725045498833;13789.586312410644;13944.790828137384;11367.48846301935;11413.828792568283;11600.18798684084;11722.58970936013;11832.290062596272;9450.43297402158;11569;11610.825837612612;11991.520339186334;12034.157608967638;11764.176507644383;11802.108483421616;12030.398480076135;12056.578019769266;12080.697982550506;8589.226969572768;8615.741527754177;8823.558822059;9039.050899655704;9044.366894317334;11354.375509347065;11541.884581154081;11593.826092608786;11720.824187637021;11895.881041189477 05:42:09 INFO - PID 1637 | |3;mootools.html;1185.5791194126086;1242.2484396939851;1428.1573871415862;1440.315849971263;1456.4101538876755;9928.305018648698;10729.731756706067;10937.125029997602;10963.945180274097;11021.724456888582;10754.784904301909;10785.245032847704;10935.179861510398;10968.012878840902;10970.506327215275;2336.4392545789015;2365.7161140663056;2371.6442533619925;2372.406898275431;2376.1683410806168;2127.4362293992103;2325.1164557468223;2417.9153729619466;2439.670379643099;2458.090506512584;2398.424378149249;2417.3231495181376;2430.064425196303;2441.145599040339;2443.499082688045;2302.804261637763;2376.287113865842;2382.380581048931;2396.9880150599224;2398;2029.1984666056826;2117.8941052947443;2335.602947498929;2386.570417324881;2440.8779561022056;10870.945645271722;10878.564857405694;10928.289661171999;10954.671359859218;11148.331100134017;6128.540359473012;6323.683815809237;6352.110704501328;6387.073874288202;6428.260750013762;3381.205416727067;3729.098159938451;3939.192465544585;4002.2195671844;4092.7135100542973;1206.0713250796925;1530.273120267875;1561.6564355841697;1592.9123898185594;1608.7747715319865;14930.402783888632;14936.62658433537;15018.69962600742;15044.247787610575;15094.773578396223;5119.718415487184;5315.441878602691;5330.093884039723;5402.324709411324;5419.891602167934;11228.101751859831;11261.605843795427;11269.943650281695;11288.435578221057;11340.94329528347;6263.091851681481;6263.185785847811;6286.339934306923;6289.622622642656;6294.968525157345;5441.700706461103;5742.799002034909;5787.623804452782;5792.21805056312;5878.147668588116;4129.525104613008;4257.1698518788535;4261.296886013835;4300.935485967713;4324.3945847580735;1232.2236990695983;1256.6117949478507;1271.110222844013;1271.4469205895466;1276.3107921722167;569.8432930943941;769.9807504812425;780.9062912450543;783.3341659589418;791.291793844506;5792.247007888947;6374.362563743682;6620.933790662032;6671.8999215010845;6729.528932974645;555.2115995286703;574.4915749561585;581.8080033588906;585.5023230254335;585.9326177489643;3129.4523458394688;3656.835442405045;3679.687226585717;3750.812459377075;3775.754575952554;13627.386767595483;13874;13951.860481395057;14102.224377659328;14113;13157.736845263042;13953;14012;14088;14094;8456.66173353059;10414.531346089445;10428.270021098602;10431;10472.528736206888;5142.151544995034;6554.63949482783;6707.826130427254;6759.695813688298;6818.590884546943;11192;11758.118141139277;12216.38918054076;12248.938755306166;12254.816177757519;10084.193264538966;11232.382218977707;11965.444492215956;12354.75290494203;12418.316992565495;8138.796530086795;8952.091619531078;8962.462252264884;9155.86266205994;9224.40041397307;10198.133158681298;10203.795924081625;10303.175745940456;10316.690499285034;10327.845082323922;9013;9016.549172541216;9030.142136497023;9112.0887911211;9174.26605871542;8691.261242794433;8822.5588720565;8832.337574681796;8858.911410885808;8866.468011919305;7680.5391676499585;7691.692332306645;7698.153203147537;7711.537307761552;7830.608469576612;5723.399043100581;5761.683107429131;5814.622049566765;5842.415758424124;5980.0431930890845;5794.391588883106;5903.822885313448;5919.940800591939;5943.1279147068535;5956.493698035542 05:42:09 INFO - PID 1637 | |4;prototype.html;356.76810073452253;400.569387907999;401.18158955730314;406.25452295038605;417.1032280596718;5643.322801263849;6123;6234.443878010017;6241.283646997078;6266.558691500957;5598.106669595871;5598.916016259759;6105.755769769204;6208.813735587928;6239.532035097363;4508.842190523333;4656.674032817704;4662.417197850269;4685.2269375553;4707.8587642370785;3825.6939444844347;3957.6042395760337;4691.859244222662;4697.788599513013;4701.777537840152;3796.620337966195;4628.940121645869;4633.837815676452;4637.698549594265;4652.162610730067;4248.766317852517;4504.684672072956;4582.954170458286;4620.953790462086;4652.860414187563;3647;3783.735138540276;3973.781442020688;3984.780837053961;3991.760494370318;5681.460261275172;6050.21347224858;6061.908641518669;6094.39056094387;6099;4073.51117865858;5340.839774806762;5353.973230133864;5408.783648654049;5428.511433970922;5447.727613619343;6561.93438065618;6585.868282634368;6610.239822420435;6626.834329141761;3711.2391959648207;4418.911621767546;4421.7789110544345;4437.866863994085;4448.933266001012;5491.890162196733;6585.649941761927;6708.865822683568;6947;7044.612546309951;4196.91606167881;4203.642690371292;4702.294655801588;4840.34655321527;4886.169351210303;4363.956360436418;4932.062908047459;4997.575206107522;5037.5214354636255;5045.293658887758;5276.947230527722;5393.73678924253;5613.635113717595;5619.297587801525;5659.801906933238;5994.67029313392;6377.362263773585;6526.314736952647;6648.434883034899;6682.766103186365;1522.763971584384;1707.5485837038418;1722.2508208929155;1729.7405389191722;1736.4703765351437;998.226374559722;1075.1828610256262;1117.1230583991528;1176.5117476247433;1201.3993003498251;4988.975055124701;5172.508611681884;5958.195643588147;6137.693115344215;6181.690915454299;6514.315996820265;6860.6912688929115;6867.313268673092;6903.240643529208;6931.861362772817;5916.023856063702;6069.817905462844;6112.602680825732;6252.718627661766;6268.592541484789;3218.436773564617;3546.8226588670464;3585.8027808470783;3586.1393265616066;3637.3813652924723;6730.259671436138;7083.574985500886;7171.247019062923;7251.709931602677;7264.382527485117;6395.712192951329;6734.764283250062;7070.929290707027;7106.538075025211;7155.745940028874;6816.318368163144;6873.518853680294;7170.462215333767;7173.928260717326;7178.676959536938;5760.33756118035;5996.5502587305255;6785;6911.5853048817235;6929.713194645856;6408.967955160194;6425.22897252314;6544.756496265694;6762.256151823197;6897.55165914214;6045.395460453919;7020.719171233093;7043.823904402431;7206.207317195;7213.71145154188;4406.955930440655;4495.393121928629;4502.639788816821;4512.691319517435;4529.252673308867;6710.597364158166;6870.690818913261;6913.827154321182;6920.515563910478;6961.443084553325;4987.426445958761;5437.755301011543;6276.9372306276355;6646.667666616553;6651.968944813561;5157.587393008475;5404.405515393225;6383.329750376143;6431.163948686641;6624.503162262753;5139.306193663808;6407.967960160169;6573.5398522103;6573.868522629617;6605.735770569124;4831.371921650163;5545.639533430314;6243.41214612928;6680.565763225375;6754.324567543403;5296.841094767163;6491.709256955566;6502.992036234391;6533.216014078341;6562.868742625025 05:42:09 INFO - PID 1637 | |5;yui.html;573.9483446489816;720.4236610711431;726.5713229194778;727.5198369076411;730.8794048981919;14916;15932.442364517245;15999.440019599317;16025.919870400632;16047.438339658129;14852.48016319429;14896.925515372435;14971.775423368657;15040.323185456653;15079.924600376982;921.5300196899595;923.5197697197453;933.733885842535;948.5209968965663;952.0241752204012;955.6129767444196;960.6205548808216;961.3895176562897;962.672691284963;965.1795973422605;1000.3647683720853;1000.8448690452991;1001.5543083327922;1001.7846163074937;1002.1331548210782;992.6972273456629;996.5764550066193;997.6059456514672;999.0558921818885;999.9000099990016;738.5347231244282;742.0946445336681;743.9888401673925;744.4974642116549;745.9179490256067;16019.878608497409;16381.754273685905;16522;16562.00627962326;16760.83239167605;14636.560903172922;14658.926705366512;14798.186099764509;14804.333804978802;14856.62858428537;10418.874614950322;11236.66290011301;11686;12153.635390938285;12181.269123852597;2831.235566397082;2911.912642620725;2942.8381439020845;2967.614210152688;2977.032464449063;77548;109213;109372.4531377338;111022.77954440947;114182;9633.421994680342;9799.020097990144;10277.537510812032;11140.220184587;11872.703182420508;24668.87665561661;24753.87623061873;24904.750952490605;24990.500189996463;25022.74977250204;7724.806879827936;7822.530648161129;7853;7942.08051325092;8016.879746803803;3971.781552014667;4091.672666186698;4106.199291138199;4115.218108559364;4170.666346692257;3645.8906232813056;3893.734536275665;4054.3715724062818;4127.050778320943;4148.730332528376;738.1695592458484;744.9664765085585;750.6997201119596;752.1049950558818;768.1588660416835;467.6188906041633;490.53994218991784;501.55612283129693;531.5614617940215;539.6974723343169;8741.956290218635;9691.030896910393;9813.754656133511;10019.521791012063;10112.696619101438;457.69334545854355;536.1903525676257;565.2651552981108;565.5588640860136;578.118369486528;4330.317192005581;4438.289873620205;4676.438827340673;4680.251159814414;4806.591439727592;81637.18362816414;88881;91514.08485915189;91860;93136;78403;79675.60162199268;80813;83372.16627833765;83613;52673.6256924031;74555;75053.24946750572;75369.24630753841;76762.6161869187;10371.429571373646;10573.312734672221;10582.735431614272;10716.142708583451;10780.892191077988;35933.820330898176;36572.63427365799;36825.81587092047;36913;37363.813180933925;48212.7589362065;48972;49087.75456122697;49306.75346623244;49997.50002499928;12951;13105.2136871788;13146;13300.40148193353;13442.731145377233;14208.076475029504;14255.358508866919;14292.82224278786;14505.492307769178;14646.707065858836;13381.19712817234;13474;13720.382582783599;13985.650358741113;14215.857841421866;12943.805842912552;12974.091813572959;13306.933465332999;13527.526536571173;13785.586432407044;12884.549040783528;13182.868171318163;13467;13614.387352569356;13626.795598065839;10805.567777288821;10871.891281087404;10968.754797715903;10977.298518729609;11627.385854924072;7892.921070789219;8311.709090181814;9153.52763178864;11143.72140696489;11231.66305010851 05:42:09 INFO - PID 1637 | __end_tp_report 05:42:09 INFO - PID 1637 | __start_cc_report 05:42:09 INFO - PID 1637 | _x_x_mozilla_cycle_collect,426 05:42:09 INFO - PID 1637 | __end_cc_report 05:42:09 INFO - PID 1637 | __startTimestamp1490186529888__endTimestamp 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | ------- Summary: start ------- 05:42:09 INFO - PID 1637 | Number of tests: 6 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6039.42 Median:6271.59 stddev:737.94 (11.8%) stddev-sans-first:697.54 05:42:09 INFO - PID 1637 | Values: 2887.2 3502.3 3537.2 3543.3 3597.2 5008.4 5830.2 6063.7 6164.2 6209.8 5033.6 5975.8 6104.0 6113.0 6132.2 5496.0 5579.6 5582.7 5696.6 5725.1 4734.7 5463.8 5654.3 5663.0 5668.1 5538.5 5680.0 5731.9 5759.9 5784.9 5329.1 5631.8 5660.3 5723.3 5781.5 4440.7 5373.2 5417.8 5497.2 5589.6 6055.7 6112.3 6118.9 6148.8 6180.7 6600.3 6620.0 6639.1 6640.2 6668.4 5574.7 6429.6 6520.6 6619.7 6670.6 6248.3 6264.2 6265.3 6271.6 6285.0 6706.9 6768.3 6838.1 6859.2 6868.3 6262.0 6343.0 6453.4 6484.7 6520.3 6666.8 6706.2 6725.4 6739.2 6742.0 6541.4 6580.1 6592.9 6602.5 6639.9 5314.5 6377.2 6460.9 6571.8 6663.8 5292.9 5309.2 5312.2 5329.8 5351.4 4700.7 4739.1 4746.2 4771.3 4776.8 6108.5 6204.0 6290.9 6296.2 6322.4 6679.6 6685.8 6703.8 6728.2 6738.8 6280.2 6285.8 6291.7 6303.7 6306.1 5706.3 5917.5 5919.4 5926.3 5928.4 5723.3 6083.2 6715.9 6840.5 6950.3 6522.3 6665.5 6743.7 6746.8 6771.8 6144.0 6326.3 6680.1 6910.7 6915.6 4936.8 5453.9 6359.8 6565.0 6636.4 6481.7 6584.6 6655.5 6670.5 6738.6 5924.6 6171.6 6500.4 6544.1 6563.6 5904.9 5924.8 5973.7 6510.6 6542.6 5048.4 5166.4 5379.8 6295.3 6331.5 6526.9 6557.6 6565.9 6567.2 6578.1 4848.4 6324.9 6335.2 6380.3 6393.6 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:49968.37 Median:35534.11 stddev:97805.17 (275.2%) stddev-sans-first:98009.58 05:42:09 INFO - PID 1637 | Values: 695.4 751.8 797.4 1259.8 1266.6 36853.1 37738.0 37756.2 37775.2 37825.4 38288.6 38550.6 38595.2 38668.6 38707.0 7931.8 8107.9 8362.4 8598.4 8598.5 8556.8 8656.0 8665.2 8669.3 8671.8 8615.4 8685.4 8686.8 8693.7 8706.9 8629.6 8667.7 8670.2 8683.9 8703.1 8529.2 8533.0 8594.4 8603.4 8640.1 37502.0 37509.6 37703.0 37714.4 37768.6 3036.3 3040.8 3054.6 3063.5 3067.5 27328.7 27561.4 27658.7 27721.0 28400.7 2800.6 2816.2 2885.4 2898.8 2931.7 233766.0 239711.0 278476.0 349359.0 350233.0 45897.1 46073.0 46080.5 46440.8 46543.0 36258.6 48305.0 48773.8 48964.5 49030.8 11544.5 11845.9 14224.8 14234.4 14282.6 11636.7 11716.0 11782.5 11785.6 11787.9 13754.0 13777.7 13807.0 13820.4 13840.9 6029.7 6057.8 6079.9 6082.2 6098.3 878.9 897.0 922.2 977.1 1038.5 23672.6 23733.9 24011.9 24053.0 24224.9 1077.7 1089.7 1109.0 1129.0 1140.2 11053.9 11703.5 12260.6 12262.9 12724.0 436762.0 438643.0 439129.0 439317.0 439430.8 53965.2 54215.5 54329.2 54353.2 54387.7 1471.5 1482.0 1512.1 1523.9 1524.8 19718.7 23482.4 23658.9 23677.9 23770.6 37387.1 37558.4 37603.0 37613.1 37648.8 357686.0 359397.0 359696.0 361458.0 361867.0 30751.7 31508.2 31580.5 31644.2 32189.8 35156.3 37930.9 39325.0 40574.6 40611.0 27162.0 33474.8 33542.0 34260.3 34414.5 33444.2 33809.5 34361.1 34545.0 35564.5 34736.0 34977.5 35503.8 35632.3 35748.1 29813.6 31347.0 31351.9 31477.2 31547.7 22017.9 23592.0 23633.3 24054.9 24238.6 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:37167.56 Median:23348.09 stddev:105454.84 (451.7%) stddev-sans-first:105714.49 05:42:09 INFO - PID 1637 | Values: 379.0 392.8 429.4 429.6 450.8 18794.7 19088.9 19671.7 19738.2 20730.3 22087.9 22096.9 22123.4 23228.4 23467.8 5749.4 6250.5 6307.2 6308.7 6315.6 5355.8 5920.8 6093.5 6154.9 6264.7 6163.7 6184.1 6194.8 6201.1 6203.0 5702.0 5996.5 6123.7 6209.6 6210.9 5257.9 5534.2 5537.4 5541.6 5548.4 27170.5 27311.9 27965.3 28109.9 28262.3 2021.8 2024.6 2057.4 2089.4 2090.7 15356.8 16059.3 16156.0 16325.9 17400.7 1424.9 1645.6 1701.9 1707.6 1737.3 114806.4 139523.0 150464.2 152866.7 153626.0 10295.2 11581.5 11690.2 11933.1 12038.8 16515.4 16779.7 16814.9 16842.7 16931.8 8884.6 8910.0 9011.4 9109.5 9112.7 8358.9 8392.6 8433.5 8585.4 8586.0 6814.8 7777.3 8024.3 8119.4 8140.8 2105.3 2148.2 2231.7 2245.3 2436.6 794.9 907.3 909.8 954.2 964.8 20198.4 20878.8 20900.9 20950.3 21163.5 911.8 946.6 954.6 974.3 976.8 10118.8 10782.6 10784.0 10812.2 10882.1 580470.0 581193.0 589778.5 688410.0 704840.0 97688.5 116581.4 120920.0 125370.4 128765.0 1017.6 1125.6 1245.8 1255.1 1256.8 21180.3 21225.4 21298.5 21532.8 21603.9 49671.0 54033.2 58558.8 61676.7 62061.0 74328.0 75968.6 80871.4 89423.1 90420.5 8551.9 8585.2 8630.3 8925.2 8989.6 13252.8 13464.4 13747.7 13789.6 13944.8 11367.5 11413.8 11600.2 11722.6 11832.3 9450.4 11569.0 11610.8 11991.5 12034.2 11764.2 11802.1 12030.4 12056.6 12080.7 8589.2 8615.7 8823.6 9039.1 9044.4 11354.4 11541.9 11593.8 11720.8 11895.9 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6724.44 Median:2442.32 stddev:4163.79 (170.5%) stddev-sans-first:4154.66 05:42:09 INFO - PID 1637 | Values: 1185.6 1242.2 1428.2 1440.3 1456.4 9928.3 10729.7 10937.1 10963.9 11021.7 10754.8 10785.2 10935.2 10968.0 10970.5 2336.4 2365.7 2371.6 2372.4 2376.2 2127.4 2325.1 2417.9 2439.7 2458.1 2398.4 2417.3 2430.1 2441.1 2443.5 2302.8 2376.3 2382.4 2397.0 2398.0 2029.2 2117.9 2335.6 2386.6 2440.9 10870.9 10878.6 10928.3 10954.7 11148.3 6128.5 6323.7 6352.1 6387.1 6428.3 3381.2 3729.1 3939.2 4002.2 4092.7 1206.1 1530.3 1561.7 1592.9 1608.8 14930.4 14936.6 15018.7 15044.2 15094.8 5119.7 5315.4 5330.1 5402.3 5419.9 11228.1 11261.6 11269.9 11288.4 11340.9 6263.1 6263.2 6286.3 6289.6 6295.0 5441.7 5742.8 5787.6 5792.2 5878.1 4129.5 4257.2 4261.3 4300.9 4324.4 1232.2 1256.6 1271.1 1271.4 1276.3 569.8 770.0 780.9 783.3 791.3 5792.2 6374.4 6620.9 6671.9 6729.5 555.2 574.5 581.8 585.5 585.9 3129.5 3656.8 3679.7 3750.8 3775.8 13627.4 13874.0 13951.9 14102.2 14113.0 13157.7 13953.0 14012.0 14088.0 14094.0 8456.7 10414.5 10428.3 10431.0 10472.5 5142.2 6554.6 6707.8 6759.7 6818.6 11192.0 11758.1 12216.4 12248.9 12254.8 10084.2 11232.4 11965.4 12354.8 12418.3 8138.8 8952.1 8962.5 9155.9 9224.4 10198.1 10203.8 10303.2 10316.7 10327.8 9013.0 9016.5 9030.1 9112.1 9174.3 8691.3 8822.6 8832.3 8858.9 8866.5 7680.5 7691.7 7698.2 7711.5 7830.6 5723.4 5761.7 5814.6 5842.4 5980.0 5794.4 5903.8 5919.9 5943.1 5956.5 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5331.75 Median:5838.18 stddev:1657.83 (28.4%) stddev-sans-first:1619.88 05:42:09 INFO - PID 1637 | Values: 356.8 400.6 401.2 406.3 417.1 5643.3 6123.0 6234.4 6241.3 6266.6 5598.1 5598.9 6105.8 6208.8 6239.5 4508.8 4656.7 4662.4 4685.2 4707.9 3825.7 3957.6 4691.9 4697.8 4701.8 3796.6 4628.9 4633.8 4637.7 4652.2 4248.8 4504.7 4583.0 4621.0 4652.9 3647.0 3783.7 3973.8 3984.8 3991.8 5681.5 6050.2 6061.9 6094.4 6099.0 4073.5 5340.8 5354.0 5408.8 5428.5 5447.7 6561.9 6585.9 6610.2 6626.8 3711.2 4418.9 4421.8 4437.9 4448.9 5491.9 6585.6 6708.9 6947.0 7044.6 4196.9 4203.6 4702.3 4840.3 4886.2 4364.0 4932.1 4997.6 5037.5 5045.3 5276.9 5393.7 5613.6 5619.3 5659.8 5994.7 6377.4 6526.3 6648.4 6682.8 1522.8 1707.5 1722.3 1729.7 1736.5 998.2 1075.2 1117.1 1176.5 1201.4 4989.0 5172.5 5958.2 6137.7 6181.7 6514.3 6860.7 6867.3 6903.2 6931.9 5916.0 6069.8 6112.6 6252.7 6268.6 3218.4 3546.8 3585.8 3586.1 3637.4 6730.3 7083.6 7171.2 7251.7 7264.4 6395.7 6734.8 7070.9 7106.5 7155.7 6816.3 6873.5 7170.5 7173.9 7178.7 5760.3 5996.6 6785.0 6911.6 6929.7 6409.0 6425.2 6544.8 6762.3 6897.6 6045.4 7020.7 7043.8 7206.2 7213.7 4407.0 4495.4 4502.6 4512.7 4529.3 6710.6 6870.7 6913.8 6920.5 6961.4 4987.4 5437.8 6276.9 6646.7 6652.0 5157.6 5404.4 6383.3 6431.2 6624.5 5139.3 6408.0 6573.5 6573.9 6605.7 4831.4 5545.6 6243.4 6680.6 6754.3 5296.8 6491.7 6503.0 6533.2 6562.9 05:42:09 INFO - PID 1637 | 05:42:09 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:18886.59 Median:37138.41 stddev:26419.61 (71.1%) stddev-sans-first:26457.94 05:42:09 INFO - PID 1637 | Values: 573.9 720.4 726.6 727.5 730.9 14916.0 15932.4 15999.4 16025.9 16047.4 14852.5 14896.9 14971.8 15040.3 15079.9 921.5 923.5 933.7 948.5 952.0 955.6 960.6 961.4 962.7 965.2 1000.4 1000.8 1001.6 1001.8 1002.1 992.7 996.6 997.6 999.1 999.9 738.5 742.1 744.0 744.5 745.9 16019.9 16381.8 16522.0 16562.0 16760.8 14636.6 14658.9 14798.2 14804.3 14856.6 10418.9 11236.7 11686.0 12153.6 12181.3 2831.2 2911.9 2942.8 2967.6 2977.0 77548.0 109213.0 109372.5 111022.8 114182.0 9633.4 9799.0 10277.5 11140.2 11872.7 24668.9 24753.9 24904.8 24990.5 25022.7 7724.8 7822.5 7853.0 7942.1 8016.9 3971.8 4091.7 4106.2 4115.2 4170.7 3645.9 3893.7 4054.4 4127.1 4148.7 738.2 745.0 750.7 752.1 768.2 467.6 490.5 501.6 531.6 539.7 8742.0 9691.0 9813.8 10019.5 10112.7 457.7 536.2 565.3 565.6 578.1 4330.3 4438.3 4676.4 4680.3 4806.6 81637.2 88881.0 91514.1 91860.0 93136.0 78403.0 79675.6 80813.0 83372.2 83613.0 52673.6 74555.0 75053.2 75369.2 76762.6 10371.4 10573.3 10582.7 10716.1 10780.9 35933.8 36572.6 36825.8 36913.0 37363.8 48212.8 48972.0 49087.8 49306.8 49997.5 12951.0 13105.2 13146.0 13300.4 13442.7 14208.1 14255.4 14292.8 14505.5 14646.7 13381.2 13474.0 13720.4 13985.7 14215.9 12943.8 12974.1 13306.9 13527.5 13785.6 12884.5 13182.9 13467.0 13614.4 13626.8 10805.6 10871.9 10968.8 10977.3 11627.4 7892.9 8311.7 9153.5 11143.7 11231.7 05:42:09 INFO - PID 1637 | -------- Summary: end -------- 05:42:09 INFO - PID 1637 | 05:42:10 INFO - TEST-INFO | 1637: exit 0 05:42:10 INFO - TEST-OK | dromaeo_css | took 1119132ms 05:42:10 INFO - TEST-START | kraken 05:42:10 INFO - operating with platform_type : mac_ 05:42:10 INFO - Initialising browser for kraken test... 05:42:10 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp_ynhbd/profile http://localhost:49242/getInfo.html) 05:42:13 INFO - PID 1649 | 2017-03-22 05:42:13.652 plugin-container[1651:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 05:42:13 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:13 INFO - PID 1649 | 2017-03-22 05:42:13.653 plugin-container[1651:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 05:42:13 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:13 INFO - PID 1649 | 2017-03-22 05:42:13.653 plugin-container[1651:10097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:13 INFO - PID 1649 | __metrics Screen width/height:1600/1200 05:42:13 INFO - PID 1649 | colorDepth:24 05:42:13 INFO - PID 1649 | Browser inner width/height: 1024/689 05:42:13 INFO - PID 1649 | __metrics 05:42:13 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:42:14 INFO - TEST-INFO | 1649: exit 0 05:42:14 INFO - Browser initialized. 05:42:14 INFO - Running cycle 1/1 for kraken test... 05:42:14 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp_ynhbd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:16 INFO - PID 1652 | 2017-03-22 05:42:16.990 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 05:42:16 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:16 INFO - PID 1652 | 2017-03-22 05:42:16.991 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.CFPasteboardClient' 05:42:16 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:16 INFO - PID 1652 | 2017-03-22 05:42:16.991 plugin-container[1653:10230] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:26 INFO - PID 1652 | RSS: Main: 227926016 05:42:26 INFO - PID 1652 | 05:42:29 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:30 INFO - PID 1652 | RSS: Main: 241971200 05:42:30 INFO - PID 1652 | 05:42:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 05:42:33 INFO - PID 1652 | RSS: Main: 250454016 05:42:33 INFO - PID 1652 | 05:42:37 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 05:42:37 INFO - PID 1652 | RSS: Main: 253181952 05:42:37 INFO - PID 1652 | 05:42:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 05:42:40 INFO - PID 1652 | RSS: Main: 255647744 05:42:40 INFO - PID 1652 | 05:42:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:42 INFO - PID 1652 | RSS: Main: 256053248 05:42:42 INFO - PID 1652 | 05:42:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 05:42:46 INFO - PID 1652 | RSS: Main: 261726208 05:42:46 INFO - PID 1652 | 05:42:50 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 05:42:50 INFO - PID 1652 | RSS: Main: 261992448 05:42:50 INFO - PID 1652 | 05:42:53 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 05:42:54 INFO - PID 1652 | RSS: Main: 261849088 05:42:54 INFO - PID 1652 | 05:42:56 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:42:56 INFO - PID 1652 | RSS: Main: 261918720 05:42:56 INFO - PID 1652 | 05:42:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:59 INFO - PID 1652 | RSS: Main: 261713920 05:42:59 INFO - PID 1652 | 05:43:01 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:02 INFO - PID 1652 | RSS: Main: 261804032 05:43:02 INFO - PID 1652 | 05:43:05 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:06 INFO - PID 1652 | RSS: Main: 262135808 05:43:06 INFO - PID 1652 | 05:43:09 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:09 INFO - PID 1652 | RSS: Main: 261902336 05:43:09 INFO - PID 1652 | 05:43:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 05:43:12 INFO - PID 1652 | RSS: Main: 264921088 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | __start_tp_report 05:43:12 INFO - PID 1652 | _x_x_mozilla_page_load 05:43:12 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:43:12 INFO - PID 1652 | |i|pagename|runs| 05:43:12 INFO - PID 1652 | |0;ai-astar;102;119;113;115;120;160;116;115;117;114 05:43:12 INFO - PID 1652 | |1;audio-beat-detection;152;130;117;149;121;148;95;154;159;149 05:43:12 INFO - PID 1652 | |2;audio-dft;197;206;200;222;228;196;199;194;199;196 05:43:12 INFO - PID 1652 | |3;audio-fft;79;60;58;84;58;61;60;61;74;65 05:43:12 INFO - PID 1652 | |4;audio-oscillator;86;71;85;71;73;70;83;98;106;121 05:43:12 INFO - PID 1652 | |5;imaging-gaussian-blur;88;88;96;84;138;130;101;138;103;88 05:43:12 INFO - PID 1652 | |6;imaging-darkroom;97;96;147;145;98;126;108;156;156;97 05:43:12 INFO - PID 1652 | |7;imaging-desaturate;141;146;91;95;90;100;91;91;91;92 05:43:12 INFO - PID 1652 | |8;json-parse-financial;67;68;101;108;108;68;68;69;68;70 05:43:12 INFO - PID 1652 | |9;json-stringify-tinderbox;57;60;59;61;65;59;59;61;59;59 05:43:12 INFO - PID 1652 | |10;stanford-crypto-aes;89;96;112;109;85;83;86;88;85;87 05:43:12 INFO - PID 1652 | |11;stanford-crypto-ccm;144;144;139;148;145;147;152;143;219;161 05:43:12 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;191;193;192;199;185;215;246;197;194;200 05:43:12 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;86;102;102;73;69;72;77;97;104;101 05:43:12 INFO - PID 1652 | __end_tp_report 05:43:12 INFO - PID 1652 | __start_cc_report 05:43:12 INFO - PID 1652 | _x_x_mozilla_cycle_collect,990 05:43:12 INFO - PID 1652 | __end_cc_report 05:43:12 INFO - PID 1652 | __startTimestamp1490186592094__endTimestamp 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | ------- Summary: start ------- 05:43:12 INFO - PID 1652 | Number of tests: 14 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:119.10 Median:116.50 stddev:15.19 (13.0%) stddev-sans-first:14.80 05:43:12 INFO - PID 1652 | Values: 102.0 119.0 113.0 115.0 120.0 160.0 116.0 115.0 117.0 114.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:150.50 stddev:20.75 (13.8%) stddev-sans-first:21.32 05:43:12 INFO - PID 1652 | Values: 152.0 130.0 117.0 149.0 121.0 148.0 95.0 154.0 159.0 149.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:203.70 Median:199.50 stddev:11.77 (5.9%) stddev-sans-first:12.23 05:43:12 INFO - PID 1652 | Values: 197.0 206.0 200.0 222.0 228.0 196.0 199.0 194.0 199.0 196.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:66.00 Median:63.00 stddev:9.48 (15.0%) stddev-sans-first:8.80 05:43:12 INFO - PID 1652 | Values: 79.0 60.0 58.0 84.0 58.0 61.0 60.0 61.0 74.0 65.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:78.00 stddev:17.17 (22.0%) stddev-sans-first:18.21 05:43:12 INFO - PID 1652 | Values: 86.0 71.0 85.0 71.0 73.0 70.0 83.0 98.0 106.0 121.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:105.40 Median:86.00 stddev:21.63 (25.2%) stddev-sans-first:22.01 05:43:12 INFO - PID 1652 | Values: 88.0 88.0 96.0 84.0 138.0 130.0 101.0 138.0 103.0 88.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:122.60 Median:126.00 stddev:26.20 (20.8%) stddev-sans-first:26.10 05:43:12 INFO - PID 1652 | Values: 97.0 96.0 147.0 145.0 98.0 126.0 108.0 156.0 156.0 97.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:91.00 stddev:21.68 (23.8%) stddev-sans-first:18.06 05:43:12 INFO - PID 1652 | Values: 141.0 146.0 91.0 95.0 90.0 100.0 91.0 91.0 91.0 92.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:79.50 Median:68.00 stddev:18.17 (26.7%) stddev-sans-first:18.70 05:43:12 INFO - PID 1652 | Values: 67.0 68.0 101.0 108.0 108.0 68.0 68.0 69.0 68.0 70.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:59.50 stddev:2.13 (3.6%) stddev-sans-first:1.99 05:43:12 INFO - PID 1652 | Values: 57.0 60.0 59.0 61.0 65.0 59.0 59.0 61.0 59.0 59.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:86.50 stddev:10.38 (12.0%) stddev-sans-first:10.95 05:43:12 INFO - PID 1652 | Values: 89.0 96.0 112.0 109.0 85.0 83.0 86.0 88.0 85.0 87.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:147.50 stddev:23.55 (16.0%) stddev-sans-first:24.68 05:43:12 INFO - PID 1652 | Values: 144.0 144.0 139.0 148.0 145.0 147.0 152.0 143.0 219.0 161.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.20 Median:198.00 stddev:17.61 (8.9%) stddev-sans-first:18.29 05:43:12 INFO - PID 1652 | Values: 191.0 193.0 192.0 199.0 185.0 215.0 246.0 197.0 194.0 200.0 05:43:12 INFO - PID 1652 | 05:43:12 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:88.30 Median:72.50 stddev:14.39 (19.9%) stddev-sans-first:15.24 05:43:12 INFO - PID 1652 | Values: 86.0 102.0 102.0 73.0 69.0 72.0 77.0 97.0 104.0 101.0 05:43:12 INFO - PID 1652 | -------- Summary: end -------- 05:43:12 INFO - PID 1652 | 05:43:12 INFO - TEST-INFO | 1652: exit 0 05:43:13 INFO - TEST-OK | kraken | took 62275ms 05:43:13 INFO - SUITE-END | took 1181s 05:43:13 INFO - Completed test suite (00:19:41) 05:43:13 INFO - javascript benchmark 05:43:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7605.712560290277, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2887.2060183449557, 3502.334556434276, 3537.1510837399014, 3543.3444812709668, 3597.2086141048967, 5008.424031236409, 5830.212921255629, 6063.666498342589, 6164.167837341957, 6209.844753881155, 5033.622478314122, 5975.834712231117, 6104.0347610180015, 6113.0, 6132.1721567588365, 5496.0107180707555, 5579.581531385142, 5582.748776305076, 5696.601237913348, 5725.14122881569, 4734.739589322586, 5463.754131064092, 5654.293213348332, 5663.008973429633, 5668.149777533362, 5538.501534861862, 5679.977604031273, 5731.942680573183, 5759.856003599902, 5784.942150578482, 5329.120695085307, 5631.831045068655, 5660.3490598581275, 5723.284589426322, 5781.479666829984, 4440.666949978732, 5373.247745315659, 5417.810376636839, 5497.175423686479, 5589.636673616202, 6055.697215139226, 6112.266528016622, 6118.938810611881, 6148.754049838001, 6180.721867515973, 6600.306967768412, 6620.006998950148, 6639.070530125786, 6640.236372817139, 6668.399844014013, 5574.721263936787, 6429.582077165016, 6520.641364724938, 6619.669016549202, 6670.6331151786635, 6248.31268560458, 6264.248290205159, 6265.310815810257, 6271.592346497554, 6284.994400895926, 6706.865862682816, 6768.289329620417, 6838.076859623887, 6859.2454829968665, 6868.278830722802, 6261.96869015652, 6343.0485427186295, 6453.354664533509, 6484.675766211671, 6520.347965203442, 6666.799996000128, 6706.161729783777, 6725.361090696376, 6739.157605299338, 6741.966290168616, 6541.378569035934, 6580.144581204413, 6592.9340706593275, 6602.504812139109, 6639.867202656016, 5314.468553144732, 6377.234731832117, 6460.903086453707, 6571.80284591463, 6663.800085997428, 5292.867678308073, 5309.150535914235, 5312.203169524602, 5329.786808527693, 5351.357837059578, 4700.670953033323, 4739.146953548395, 4746.240601503743, 4771.308160316735, 4776.785044672998, 6108.493847774667, 6204.007358822657, 6290.905636415458, 6296.212973378328, 6322.430981211621, 6679.56582822115, 6685.832854178587, 6703.798886033427, 6728.192616886005, 6738.831529211809, 6280.246370435577, 6285.779997700059, 6291.716872740738, 6303.716332765037, 6306.054091886162, 5706.286714160813, 5917.52659787216, 5919.437653422918, 5926.2888453385, 5928.436798504135, 5723.3418156912485, 6083.178770865877, 6715.899261511179, 6840.452763778786, 6950.304969503211, 6522.34776522344, 6665.466762658879, 6743.662816859235, 6746.763863264762, 6771.796846094625, 6144.003198960267, 6326.335734747785, 6680.0647909291765, 6910.689018994057, 6915.550489218184, 4936.75316234194, 5453.945460545344, 6359.8092057238355, 6564.98242772371, 6636.369544893228, 6481.675916204076, 6584.572002819801, 6655.467562594884, 6670.499712521678, 6738.59568425896, 5924.585279030426, 6171.56799024064, 6500.447461965786, 6544.083828263952, 6563.573367731082, 5904.940950590611, 5924.815036992705, 5973.671448070398, 6510.609363438209, 6542.640154791532, 5048.3941926969, 5166.3800343958965, 5379.757910893941, 6295.276043254901, 6331.5251356147555, 6526.869462610816, 6557.606543607399, 6565.868682626226, 6567.2447668518735, 6578.079068930258, 4848.369711937426, 6324.873502530016, 6335.239771227482, 6380.298167201697, 6393.552451328363], "value": 5993.907491002591, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.3811108113778, 751.8045308219861, 797.3899966525607, 1259.8236246925433, 1266.556705153197, 36853.07867303315, 37738.0, 37756.244875102515, 37775.244495110084, 37825.43261851074, 38288.617113828856, 38550.61449385505, 38595.22809543808, 38668.61331386685, 38707.0, 7931.8413631727335, 8107.945967024279, 8362.414630975834, 8598.355123365742, 8598.527081010541, 8556.78608034801, 8655.95672021639, 8665.17680820321, 8669.349798765117, 8671.826563468727, 8615.35384846136, 8685.435446695976, 8686.826263474726, 8693.739187824376, 8706.869396959051, 8629.568521573927, 8667.696630617898, 8670.176333248362, 8683.869741953877, 8703.086175951528, 8529.23236908675, 8532.957335213347, 8594.441361311494, 8603.440776349516, 8640.135986401372, 37502.0, 37509.62490375089, 37703.0, 37714.43428348577, 37768.62231377679, 3036.2917714696227, 3040.7567394608377, 3054.5570892220726, 3063.479208534532, 3067.5245336972803, 27328.726712732816, 27561.44877102467, 27658.723412765816, 27721.0, 28400.721967511512, 2800.579913013044, 2816.197383745643, 2885.365219651694, 2898.8260704357594, 2931.6775154732964, 233766.0, 239711.0, 278476.0, 349359.0, 350233.0, 45897.08205835865, 46073.0, 46080.5391946083, 46440.83897902512, 46543.0, 36258.63741362606, 48305.03389932182, 48773.75613121912, 48964.51035489671, 49030.75484622626, 11544.480498377594, 11845.933865952105, 14224.786628200585, 14234.359453824603, 14282.57152285433, 11636.709082273012, 11716.0, 11782.52869885195, 11785.646430607096, 11787.939085482347, 13754.0, 13777.655558611115, 13807.0, 13820.447182112603, 13840.861591383957, 6029.698515074228, 6057.757689692451, 6079.908801367983, 6082.171790117616, 6098.26820781509, 878.9340799440067, 896.950667713269, 922.1700469577434, 977.1205914676848, 1038.4963292802984, 23672.64491032636, 23733.881330593238, 24011.879940600185, 24053.0, 24224.87887560586, 1077.7305673581604, 1089.6730980705756, 1108.9500972456258, 1128.9887101129048, 1140.2132528555271, 11053.889461105286, 11703.473343699554, 12260.632181034583, 12262.938685306517, 12723.982081433649, 436762.0, 438643.0, 439129.0, 439317.0, 439430.8028459837, 53965.1905221422, 54215.45784542183, 54329.185062224096, 54353.1847022295, 54387.72806135944, 1471.492335144395, 1482.0, 1512.138081293652, 1523.916184609857, 1524.7712843073627, 19718.704219436433, 23482.412939676644, 23658.88170559136, 23677.881610591838, 23770.643440348755, 37387.06532336713, 37558.43662345012, 37603.0, 37613.05967350838, 37648.811755941046, 357686.0, 359397.0, 359696.0, 361458.0, 361867.0, 30751.692483074883, 31508.212294692818, 31580.526292106097, 31644.208894777814, 32189.839050804596, 35156.29687406187, 37930.86207413782, 39325.0, 40574.59425405826, 40611.0, 27162.0, 33474.82838100655, 33542.0, 34260.314793704485, 34414.48378274378, 33444.163895901824, 33809.49285760667, 34361.140971475914, 34545.0, 35564.466533002545, 34736.0, 34977.475337869444, 35503.75736849198, 35632.28735425329, 35748.106297342776, 29813.5527967085, 31347.0, 31351.90268340597, 31477.213069673442, 31547.684523154476, 22017.899105044362, 23592.0, 23633.29100126999, 24054.87972560126, 24238.63642045406], "value": 17496.067649765577, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.9810509474529, 392.7977091797725, 429.4181384224376, 429.5918877066786, 450.7678545549041, 18794.718079228824, 19088.90455547724, 19671.704924426143, 19738.210471581155, 20730.27444039459, 22087.889560552176, 22096.88951555244, 22123.446913827123, 23228.41928951777, 23467.76532234677, 5749.425057494249, 6250.499960003189, 6307.180066591337, 6308.653024083674, 6315.557910946236, 5355.848492574096, 5920.822375328747, 6093.482054025413, 6154.907676384858, 6264.718087686042, 6163.691815409212, 6184.134221209034, 6194.752209911598, 6201.069839524062, 6202.96898515509, 5702.0, 5996.520278377741, 6123.663198524079, 6209.565330426872, 6210.9068363974575, 5257.921131183035, 5534.225208470817, 5537.363203231599, 5541.639793413456, 5548.4174161713245, 27170.45659086807, 27311.863440682868, 27965.300867478272, 28109.85945070282, 28262.293442663893, 2021.7674967378646, 2024.6254442928105, 2057.423921302023, 2089.39407571804, 2090.6654935210295, 15356.769648455282, 16059.277332520065, 16156.0, 16325.918370408192, 17400.65198696019, 1424.886009119268, 1645.6215070533724, 1701.8553422959064, 1707.5560354307845, 1737.2529812180758, 114806.42596786963, 139523.0, 150464.2476787609, 152866.7069993951, 153626.0, 10295.227857910686, 11581.478833452515, 11690.181687281978, 11933.105017123751, 12038.759224815454, 16515.421960231335, 16779.664406711796, 16814.91592542054, 16842.747358789627, 16931.830681693027, 8884.600192991331, 8910.0, 9011.414258073204, 9109.453432794053, 9112.726618201465, 8358.916410835815, 8392.622331995075, 8433.493990360597, 8585.441946273471, 8585.957070214734, 6814.795556133324, 7777.261160189773, 8024.317932975646, 8119.3910456715985, 8140.75577732669, 2105.2526353144717, 2148.237375731624, 2231.6875637410776, 2245.3376254005107, 2436.6223235398543, 794.9364050875917, 907.2651152566442, 909.8180363927241, 954.160338901762, 964.7781010367655, 20198.39404817858, 20878.79121208799, 20900.895495522425, 20950.266740664003, 21163.484486873385, 911.7766147293955, 946.6024269806697, 954.5895265036102, 974.3082411487782, 976.8095221431753, 10118.848217276747, 10782.622608208674, 10784.015176097677, 10812.243142980073, 10882.129429645609, 580470.0, 581193.0, 589778.4821200902, 688410.0, 704840.0, 97688.53467197997, 116581.417092914, 120920.0, 125370.37314813367, 128765.0, 1017.6031347774374, 1125.6454216921643, 1245.8255844181822, 1255.1465003797505, 1256.7800634888858, 21180.258690945742, 21225.36323910285, 21298.467538311666, 21532.784672153706, 21603.891980539996, 49671.01973881434, 54033.18950215828, 58558.77926713901, 61676.69161654163, 62061.0, 74328.0, 75968.62015689886, 80871.38257234705, 89423.10576894408, 90420.5478972601, 8551.914480855112, 8585.18440748128, 8630.309575233841, 8925.152110549487, 8989.550522473719, 13252.801207981694, 13464.394102265225, 13747.725045498833, 13789.586312410644, 13944.790828137384, 11367.48846301935, 11413.828792568283, 11600.18798684084, 11722.58970936013, 11832.290062596272, 9450.43297402158, 11569.0, 11610.825837612612, 11991.520339186334, 12034.157608967638, 11764.176507644383, 11802.108483421616, 12030.398480076135, 12056.578019769266, 12080.697982550506, 8589.226969572768, 8615.741527754177, 8823.558822059, 9039.050899655704, 9044.366894317334, 11354.375509347065, 11541.884581154081, 11593.826092608786, 11720.824187637021, 11895.881041189477], "value": 10260.058079059805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1185.5791194126086, 1242.2484396939851, 1428.1573871415862, 1440.315849971263, 1456.4101538876755, 9928.305018648698, 10729.731756706067, 10937.125029997602, 10963.945180274097, 11021.724456888582, 10754.784904301909, 10785.245032847704, 10935.179861510398, 10968.012878840902, 10970.506327215275, 2336.4392545789015, 2365.7161140663056, 2371.6442533619925, 2372.406898275431, 2376.1683410806168, 2127.4362293992103, 2325.1164557468223, 2417.9153729619466, 2439.670379643099, 2458.090506512584, 2398.424378149249, 2417.3231495181376, 2430.064425196303, 2441.145599040339, 2443.499082688045, 2302.804261637763, 2376.287113865842, 2382.380581048931, 2396.9880150599224, 2398.0, 2029.1984666056826, 2117.8941052947443, 2335.602947498929, 2386.570417324881, 2440.8779561022056, 10870.945645271722, 10878.564857405694, 10928.289661171999, 10954.671359859218, 11148.331100134017, 6128.540359473012, 6323.683815809237, 6352.110704501328, 6387.073874288202, 6428.260750013762, 3381.205416727067, 3729.098159938451, 3939.192465544585, 4002.2195671844, 4092.7135100542973, 1206.0713250796925, 1530.273120267875, 1561.6564355841697, 1592.9123898185594, 1608.7747715319865, 14930.402783888632, 14936.62658433537, 15018.69962600742, 15044.247787610575, 15094.773578396223, 5119.718415487184, 5315.441878602691, 5330.093884039723, 5402.324709411324, 5419.891602167934, 11228.101751859831, 11261.605843795427, 11269.943650281695, 11288.435578221057, 11340.94329528347, 6263.091851681481, 6263.185785847811, 6286.339934306923, 6289.622622642656, 6294.968525157345, 5441.700706461103, 5742.799002034909, 5787.623804452782, 5792.21805056312, 5878.147668588116, 4129.525104613008, 4257.1698518788535, 4261.296886013835, 4300.935485967713, 4324.3945847580735, 1232.2236990695983, 1256.6117949478507, 1271.110222844013, 1271.4469205895466, 1276.3107921722167, 569.8432930943941, 769.9807504812425, 780.9062912450543, 783.3341659589418, 791.291793844506, 5792.247007888947, 6374.362563743682, 6620.933790662032, 6671.8999215010845, 6729.528932974645, 555.2115995286703, 574.4915749561585, 581.8080033588906, 585.5023230254335, 585.9326177489643, 3129.4523458394688, 3656.835442405045, 3679.687226585717, 3750.812459377075, 3775.754575952554, 13627.386767595483, 13874.0, 13951.860481395057, 14102.224377659328, 14113.0, 13157.736845263042, 13953.0, 14012.0, 14088.0, 14094.0, 8456.66173353059, 10414.531346089445, 10428.270021098602, 10431.0, 10472.528736206888, 5142.151544995034, 6554.63949482783, 6707.826130427254, 6759.695813688298, 6818.590884546943, 11192.0, 11758.118141139277, 12216.38918054076, 12248.938755306166, 12254.816177757519, 10084.193264538966, 11232.382218977707, 11965.444492215956, 12354.75290494203, 12418.316992565495, 8138.796530086795, 8952.091619531078, 8962.462252264884, 9155.86266205994, 9224.40041397307, 10198.133158681298, 10203.795924081625, 10303.175745940456, 10316.690499285034, 10327.845082323922, 9013.0, 9016.549172541216, 9030.142136497023, 9112.0887911211, 9174.26605871542, 8691.261242794433, 8822.5588720565, 8832.337574681796, 8858.911410885808, 8866.468011919305, 7680.5391676499585, 7691.692332306645, 7698.153203147537, 7711.537307761552, 7830.608469576612, 5723.399043100581, 5761.683107429131, 5814.622049566765, 5842.415758424124, 5980.0431930890845, 5794.391588883106, 5903.822885313448, 5919.940800591939, 5943.1279147068535, 5956.493698035542], "value": 5081.3781448887785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.76810073452253, 400.569387907999, 401.18158955730314, 406.25452295038605, 417.1032280596718, 5643.322801263849, 6123.0, 6234.443878010017, 6241.283646997078, 6266.558691500957, 5598.106669595871, 5598.916016259759, 6105.755769769204, 6208.813735587928, 6239.532035097363, 4508.842190523333, 4656.674032817704, 4662.417197850269, 4685.2269375553, 4707.8587642370785, 3825.6939444844347, 3957.6042395760337, 4691.859244222662, 4697.788599513013, 4701.777537840152, 3796.620337966195, 4628.940121645869, 4633.837815676452, 4637.698549594265, 4652.162610730067, 4248.766317852517, 4504.684672072956, 4582.954170458286, 4620.953790462086, 4652.860414187563, 3647.0, 3783.735138540276, 3973.781442020688, 3984.780837053961, 3991.760494370318, 5681.460261275172, 6050.21347224858, 6061.908641518669, 6094.39056094387, 6099.0, 4073.51117865858, 5340.839774806762, 5353.973230133864, 5408.783648654049, 5428.511433970922, 5447.727613619343, 6561.93438065618, 6585.868282634368, 6610.239822420435, 6626.834329141761, 3711.2391959648207, 4418.911621767546, 4421.7789110544345, 4437.866863994085, 4448.933266001012, 5491.890162196733, 6585.649941761927, 6708.865822683568, 6947.0, 7044.612546309951, 4196.91606167881, 4203.642690371292, 4702.294655801588, 4840.34655321527, 4886.169351210303, 4363.956360436418, 4932.062908047459, 4997.575206107522, 5037.5214354636255, 5045.293658887758, 5276.947230527722, 5393.73678924253, 5613.635113717595, 5619.297587801525, 5659.801906933238, 5994.67029313392, 6377.362263773585, 6526.314736952647, 6648.434883034899, 6682.766103186365, 1522.763971584384, 1707.5485837038418, 1722.2508208929155, 1729.7405389191722, 1736.4703765351437, 998.226374559722, 1075.1828610256262, 1117.1230583991528, 1176.5117476247433, 1201.3993003498251, 4988.975055124701, 5172.508611681884, 5958.195643588147, 6137.693115344215, 6181.690915454299, 6514.315996820265, 6860.6912688929115, 6867.313268673092, 6903.240643529208, 6931.861362772817, 5916.023856063702, 6069.817905462844, 6112.602680825732, 6252.718627661766, 6268.592541484789, 3218.436773564617, 3546.8226588670464, 3585.8027808470783, 3586.1393265616066, 3637.3813652924723, 6730.259671436138, 7083.574985500886, 7171.247019062923, 7251.709931602677, 7264.382527485117, 6395.712192951329, 6734.764283250062, 7070.929290707027, 7106.538075025211, 7155.745940028874, 6816.318368163144, 6873.518853680294, 7170.462215333767, 7173.928260717326, 7178.676959536938, 5760.33756118035, 5996.5502587305255, 6785.0, 6911.5853048817235, 6929.713194645856, 6408.967955160194, 6425.22897252314, 6544.756496265694, 6762.256151823197, 6897.55165914214, 6045.395460453919, 7020.719171233093, 7043.823904402431, 7206.207317195, 7213.71145154188, 4406.955930440655, 4495.393121928629, 4502.639788816821, 4512.691319517435, 4529.252673308867, 6710.597364158166, 6870.690818913261, 6913.827154321182, 6920.515563910478, 6961.443084553325, 4987.426445958761, 5437.755301011543, 6276.9372306276355, 6646.667666616553, 6651.968944813561, 5157.587393008475, 5404.405515393225, 6383.329750376143, 6431.163948686641, 6624.503162262753, 5139.306193663808, 6407.967960160169, 6573.5398522103, 6573.868522629617, 6605.735770569124, 4831.371921650163, 5545.639533430314, 6243.41214612928, 6680.565763225375, 6754.324567543403, 5296.841094767163, 6491.709256955566, 6502.992036234391, 6533.216014078341, 6562.868742625025], "value": 4829.644416821789, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.9483446489816, 720.4236610711431, 726.5713229194778, 727.5198369076411, 730.8794048981919, 14916.0, 15932.442364517245, 15999.440019599317, 16025.919870400632, 16047.438339658129, 14852.48016319429, 14896.925515372435, 14971.775423368657, 15040.323185456653, 15079.924600376982, 921.5300196899595, 923.5197697197453, 933.733885842535, 948.5209968965663, 952.0241752204012, 955.6129767444196, 960.6205548808216, 961.3895176562897, 962.672691284963, 965.1795973422605, 1000.3647683720853, 1000.8448690452991, 1001.5543083327922, 1001.7846163074937, 1002.1331548210782, 992.6972273456629, 996.5764550066193, 997.6059456514672, 999.0558921818885, 999.9000099990016, 738.5347231244282, 742.0946445336681, 743.9888401673925, 744.4974642116549, 745.9179490256067, 16019.878608497409, 16381.754273685905, 16522.0, 16562.00627962326, 16760.83239167605, 14636.560903172922, 14658.926705366512, 14798.186099764509, 14804.333804978802, 14856.62858428537, 10418.874614950322, 11236.66290011301, 11686.0, 12153.635390938285, 12181.269123852597, 2831.235566397082, 2911.912642620725, 2942.8381439020845, 2967.614210152688, 2977.032464449063, 77548.0, 109213.0, 109372.4531377338, 111022.77954440947, 114182.0, 9633.421994680342, 9799.020097990144, 10277.537510812032, 11140.220184587, 11872.703182420508, 24668.87665561661, 24753.87623061873, 24904.750952490605, 24990.500189996463, 25022.74977250204, 7724.806879827936, 7822.530648161129, 7853.0, 7942.08051325092, 8016.879746803803, 3971.781552014667, 4091.672666186698, 4106.199291138199, 4115.218108559364, 4170.666346692257, 3645.8906232813056, 3893.734536275665, 4054.3715724062818, 4127.050778320943, 4148.730332528376, 738.1695592458484, 744.9664765085585, 750.6997201119596, 752.1049950558818, 768.1588660416835, 467.6188906041633, 490.53994218991784, 501.55612283129693, 531.5614617940215, 539.6974723343169, 8741.956290218635, 9691.030896910393, 9813.754656133511, 10019.521791012063, 10112.696619101438, 457.69334545854355, 536.1903525676257, 565.2651552981108, 565.5588640860136, 578.118369486528, 4330.317192005581, 4438.289873620205, 4676.438827340673, 4680.251159814414, 4806.591439727592, 81637.18362816414, 88881.0, 91514.08485915189, 91860.0, 93136.0, 78403.0, 79675.60162199268, 80813.0, 83372.16627833765, 83613.0, 52673.6256924031, 74555.0, 75053.24946750572, 75369.24630753841, 76762.6161869187, 10371.429571373646, 10573.312734672221, 10582.735431614272, 10716.142708583451, 10780.892191077988, 35933.820330898176, 36572.63427365799, 36825.81587092047, 36913.0, 37363.813180933925, 48212.7589362065, 48972.0, 49087.75456122697, 49306.75346623244, 49997.50002499928, 12951.0, 13105.2136871788, 13146.0, 13300.40148193353, 13442.731145377233, 14208.076475029504, 14255.358508866919, 14292.82224278786, 14505.492307769178, 14646.707065858836, 13381.19712817234, 13474.0, 13720.382582783599, 13985.650358741113, 14215.857841421866, 12943.805842912552, 12974.091813572959, 13306.933465332999, 13527.526536571173, 13785.586432407044, 12884.549040783528, 13182.868171318163, 13467.0, 13614.387352569356, 13626.795598065839, 10805.567777288821, 10871.891281087404, 10968.754797715903, 10977.298518729609, 11627.385854924072, 7892.921070789219, 8311.709090181814, 9153.52763178864, 11143.72140696489, 11231.66305010851], "value": 7330.115909178621, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 119.0, 113.0, 115.0, 120.0, 160.0, 116.0, 115.0, 117.0, 114.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 130.0, 117.0, 149.0, 121.0, 148.0, 95.0, 154.0, 159.0, 149.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 206.0, 200.0, 222.0, 228.0, 196.0, 199.0, 194.0, 199.0, 196.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 84.0, 58.0, 61.0, 60.0, 61.0, 74.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 85.0, 71.0, 73.0, 70.0, 83.0, 98.0, 106.0, 121.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 96.0, 84.0, 138.0, 130.0, 101.0, 138.0, 103.0, 88.0], "value": 105.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 147.0, 145.0, 98.0, 126.0, 108.0, 156.0, 156.0, 97.0], "value": 122.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 91.0, 95.0, 90.0, 100.0, 91.0, 91.0, 91.0, 92.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 101.0, 108.0, 108.0, 68.0, 68.0, 69.0, 68.0, 70.0], "value": 79.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 59.0, 61.0, 65.0, 59.0, 59.0, 61.0, 59.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 112.0, 109.0, 85.0, 83.0, 86.0, 88.0, 85.0, 87.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 144.0, 139.0, 148.0, 145.0, 147.0, 152.0, 143.0, 219.0, 161.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 193.0, 192.0, 199.0, 185.0, 215.0, 246.0, 197.0, 194.0, 200.0], "value": 201.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 102.0, 102.0, 73.0, 69.0, 72.0, 77.0, 97.0, 104.0, 101.0], "value": 88.3, "unit": "score"}]}]} 05:43:13 INFO - Return code: 0 05:43:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:13 ERROR - # TBPL SUCCESS # 05:43:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:13 INFO - 2017-03-22 05:43:13.412 screenresolution[1657:10612] starting screenresolution argv=screenresolution get 05:43:13 INFO - 2017-03-22 05:43:13.417 screenresolution[1657:10612] Display 0: 1600x1200x32@60 05:43:13 INFO - 2017-03-22 05:43:13.423 screenresolution[1658:10615] starting screenresolution argv=screenresolution list 05:43:13 INFO - Available Modes on Display 0 05:43:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:13 INFO - Intel Iris: 05:43:13 INFO - Chipset Model: Intel Iris 05:43:13 INFO - Type: GPU 05:43:13 INFO - Bus: Built-In 05:43:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:13 INFO - Vendor: Intel (0x8086) 05:43:13 INFO - Device ID: 0x0a2e 05:43:13 INFO - Revision ID: 0x0009 05:43:13 INFO - Displays: 05:43:13 INFO - Display: 05:43:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:13 INFO - Display Serial Number: 1600x1200 60 05:43:13 INFO - Main Display: Yes 05:43:13 INFO - Mirror: Off 05:43:13 INFO - Online: Yes 05:43:13 INFO - Rotation: Supported 05:43:13 INFO - Return code: 0 05:43:13 INFO - Running post-action listener: _resource_record_post_action 05:43:13 INFO - [mozharness: 2017-03-22 12:43:13.661683Z] Finished run-tests step (success) 05:43:13 INFO - Running post-run listener: _resource_record_post_run 05:43:13 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39409664; Write bytes: 517469184; Read time: 253; Write time: 1638 05:43:13 INFO - TinderboxPrint: CPU usage
54.1% 05:43:13 INFO - TinderboxPrint: I/O read bytes / time
39,409,664 / 253 05:43:13 INFO - TinderboxPrint: I/O write bytes / time
517,469,184 / 1,638 05:43:13 INFO - TinderboxPrint: CPU idle
2,206.3 (45.9%) 05:43:13 INFO - TinderboxPrint: CPU system
965.0 (20.1%) 05:43:13 INFO - TinderboxPrint: CPU user
1,638.3 (34.1%) 05:43:13 INFO - TinderboxPrint: Swap in / out
249,556,992 / 0 05:43:13 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206156288; Write bytes: 204007424; Read time: 15777; Write time: 440 05:43:13 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 37410816; Write bytes: 302582784; Read time: 234; Write time: 1162 05:43:14 INFO - Running post-run listener: _upload_blobber_files 05:43:14 INFO - Blob upload gear active. 05:43:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:43:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:43:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:15 INFO - (blobuploader) - INFO - Done attempting. 05:43:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:43:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:43:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:16 INFO - (blobuploader) - INFO - Done attempting. 05:43:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:43:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:17 INFO - (blobuploader) - INFO - Done attempting. 05:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:19 INFO - (blobuploader) - INFO - Done attempting. 05:43:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:19 INFO - Return code: 0 05:43:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0565e4562b21bbe36f51d1e7851c61746cf9aa26f734839d31778b271ecad55263e87ec01ba497645de8e5aa811772c9b2f348ff0bf27ee1072a678be6d175d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d267b16762da96f97045f929c801f6e2be8d464d3c2149e48019d1a67ae7c7cdf77b39d342d0f9090ffa065355b47f98cd9658f1a124d0adf59d673dc813db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/370c4fd40d07d2896b974817b7eeee4a6d5755ef1ea3d0310f4c1e7a16bd374f90d0301f65fa452d46cefec41b09c487b62d6fc68f7dbaa9d3542c9df41d69ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c77cb066442f32fecb70e84d5ea7804788ea8e867884bc300a431905310bade48df8697afc0056f5c350aa3bbb5e730c63f6b3a07598796aadbfb2a513ef18"} 05:43:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:19 INFO - Contents: 05:43:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0565e4562b21bbe36f51d1e7851c61746cf9aa26f734839d31778b271ecad55263e87ec01ba497645de8e5aa811772c9b2f348ff0bf27ee1072a678be6d175d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d267b16762da96f97045f929c801f6e2be8d464d3c2149e48019d1a67ae7c7cdf77b39d342d0f9090ffa065355b47f98cd9658f1a124d0adf59d673dc813db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/370c4fd40d07d2896b974817b7eeee4a6d5755ef1ea3d0310f4c1e7a16bd374f90d0301f65fa452d46cefec41b09c487b62d6fc68f7dbaa9d3542c9df41d69ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c77cb066442f32fecb70e84d5ea7804788ea8e867884bc300a431905310bade48df8697afc0056f5c350aa3bbb5e730c63f6b3a07598796aadbfb2a513ef18"} 05:43:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:19 INFO - Copying logs to upload dir... 05:43:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.503491 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-22 05:43:19.674756) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:19.678232) ========= 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.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0565e4562b21bbe36f51d1e7851c61746cf9aa26f734839d31778b271ecad55263e87ec01ba497645de8e5aa811772c9b2f348ff0bf27ee1072a678be6d175d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d267b16762da96f97045f929c801f6e2be8d464d3c2149e48019d1a67ae7c7cdf77b39d342d0f9090ffa065355b47f98cd9658f1a124d0adf59d673dc813db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/370c4fd40d07d2896b974817b7eeee4a6d5755ef1ea3d0310f4c1e7a16bd374f90d0301f65fa452d46cefec41b09c487b62d6fc68f7dbaa9d3542c9df41d69ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c77cb066442f32fecb70e84d5ea7804788ea8e867884bc300a431905310bade48df8697afc0056f5c350aa3bbb5e730c63f6b3a07598796aadbfb2a513ef18"} build_url:https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/PcJeYlaFSduMIuWDvnla2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0565e4562b21bbe36f51d1e7851c61746cf9aa26f734839d31778b271ecad55263e87ec01ba497645de8e5aa811772c9b2f348ff0bf27ee1072a678be6d175d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d267b16762da96f97045f929c801f6e2be8d464d3c2149e48019d1a67ae7c7cdf77b39d342d0f9090ffa065355b47f98cd9658f1a124d0adf59d673dc813db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/370c4fd40d07d2896b974817b7eeee4a6d5755ef1ea3d0310f4c1e7a16bd374f90d0301f65fa452d46cefec41b09c487b62d6fc68f7dbaa9d3542c9df41d69ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c77cb066442f32fecb70e84d5ea7804788ea8e867884bc300a431905310bade48df8697afc0056f5c350aa3bbb5e730c63f6b3a07598796aadbfb2a513ef18"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:19.712645) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:19.713014) ========= 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.rZHkFCibSl/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.cPADND4paH/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.005287 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:19.764787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:43:19.765547) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:43:19.766342) ========= ========= Total master_lag: 0.18 =========