builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0378 starttime: 1490283035.35 results: success (0) buildid: 20170323054323 builduid: 6356f05a5c0d461da9939481e909c3e1 revision: 89b93d310da5bc7f86ebcdb8fe580d47a610757a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.349439) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.350053) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.350391) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.377178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.377617) ========= 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.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-23 08:30:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 228M=0s 2017-03-23 08:30:35 (228 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.545330) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.545667) ========= 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.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.027109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:35.594588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 08:30:35.594925) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 89b93d310da5bc7f86ebcdb8fe580d47a610757a --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 89b93d310da5bc7f86ebcdb8fe580d47a610757a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-23 08:30:35,704 truncating revision to first 12 chars 2017-03-23 08:30:35,704 Setting DEBUG logging. 2017-03-23 08:30:35,704 attempt 1/10 2017-03-23 08:30:35,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/89b93d310da5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 08:30:37,330 unpacking tar archive at: mozilla-central-89b93d310da5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.905969 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 08:30:37.524403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:37.524733) ========= script_repo_revision: 89b93d310da5bc7f86ebcdb8fe580d47a610757a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:37.525150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:37.525410) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:30:37.545071) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-23 08:30:37.545380) ========= /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.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:30:37 INFO - MultiFileLogger online at 20170323 08:30:37 in /builds/slave/test 08:30:37 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 08:30:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:37 INFO - {'append_to_log': False, 08:30:37 INFO - 'base_work_dir': '/builds/slave/test', 08:30:37 INFO - 'blob_upload_branch': 'Firefox', 08:30:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:37 INFO - 'branch': 'Firefox', 08:30:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:37 INFO - 'config_files': ('talos/mac_config.py',), 08:30:37 INFO - 'default_actions': ('clobber', 08:30:37 INFO - 'read-buildbot-config', 08:30:37 INFO - 'download-and-extract', 08:30:37 INFO - 'populate-webroot', 08:30:37 INFO - 'create-virtualenv', 08:30:37 INFO - 'install', 08:30:37 INFO - 'run-tests'), 08:30:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:37 INFO - 'download_minidump_stackwalk': True, 08:30:37 INFO - 'download_symbols': 'ondemand', 08:30:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:37 INFO - 'gecko_profile': False, 08:30:37 INFO - 'gecko_profile_interval': 0, 08:30:37 INFO - 'installer_path': 'installer.exe', 08:30:37 INFO - 'log_level': 'info', 08:30:37 INFO - 'log_name': 'talos', 08:30:37 INFO - 'log_to_console': True, 08:30:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:37 INFO - 'opt_config_files': (), 08:30:37 INFO - 'pip_index': False, 08:30:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:37 INFO - 'cmd': ('bash', 08:30:37 INFO - '-c', 08:30:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:37 INFO - 'enabled': True, 08:30:37 INFO - 'halt_on_failure': False, 08:30:37 INFO - 'name': 'check_screen_resolution'},), 08:30:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:37 INFO - 'cmd': ('bash', 08:30:37 INFO - '-c', 08:30:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:37 INFO - 'enabled': True, 08:30:37 INFO - 'halt_on_failure': False, 08:30:37 INFO - 'name': 'check_screen_resolution'},), 08:30:37 INFO - 'run_cmd_checks_enabled': True, 08:30:37 INFO - 'suite': 'dromaeojs-e10s', 08:30:37 INFO - 'system_bits': '32', 08:30:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:37 INFO - 'title': 't-yosemite-r7-0378', 08:30:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:37 INFO - 'use_talos_json': True, 08:30:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:37 INFO - 'work_dir': 'build'} 08:30:37 INFO - [mozharness: 2017-03-23 15:30:37.762177Z] Running clobber step. 08:30:37 INFO - Running pre-action listener: _resource_record_pre_action 08:30:37 INFO - Running main action method: clobber 08:30:37 INFO - rmtree: /builds/slave/test/build 08:30:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:39 INFO - Running post-action listener: _resource_record_post_action 08:30:39 INFO - [mozharness: 2017-03-23 15:30:39.202396Z] Finished clobber step (success) 08:30:39 INFO - [mozharness: 2017-03-23 15:30:39.202473Z] Running read-buildbot-config step. 08:30:39 INFO - Running pre-action listener: _resource_record_pre_action 08:30:39 INFO - Running main action method: read_buildbot_config 08:30:39 INFO - Using buildbot properties: 08:30:39 INFO - { 08:30:39 INFO - "project": "", 08:30:39 INFO - "product": "firefox", 08:30:39 INFO - "script_repo_revision": "production", 08:30:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:30:39 INFO - "repository": "", 08:30:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:30:39 INFO - "buildid": "20170323054323", 08:30:39 INFO - "pgo_build": "False", 08:30:39 INFO - "basedir": "/builds/slave/test", 08:30:39 INFO - "buildnumber": 24, 08:30:39 INFO - "slavename": "t-yosemite-r7-0378", 08:30:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:30:39 INFO - "platform": "yosemite_r7", 08:30:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:30:39 INFO - "branch": "mozilla-central", 08:30:39 INFO - "repo_path": "mozilla-central", 08:30:39 INFO - "revision": "89b93d310da5bc7f86ebcdb8fe580d47a610757a", 08:30:39 INFO - "stage_platform": "macosx64", 08:30:39 INFO - "builduid": "6356f05a5c0d461da9939481e909c3e1", 08:30:39 INFO - "slavebuilddir": "test" 08:30:39 INFO - } 08:30:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:30:39 INFO - Running post-action listener: _resource_record_post_action 08:30:39 INFO - [mozharness: 2017-03-23 15:30:39.205932Z] Finished read-buildbot-config step (success) 08:30:39 INFO - [mozharness: 2017-03-23 15:30:39.206061Z] Running download-and-extract step. 08:30:39 INFO - Running pre-action listener: _resource_record_pre_action 08:30:39 INFO - Running main action method: download_and_extract 08:30:39 INFO - mkdir: /builds/slave/test/build/tests 08:30:39 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')]} 08:30:39 INFO - trying https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:30:39 INFO - Downloading https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/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 08:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/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 08:30:40 INFO - Downloaded 1325 bytes. 08:30:40 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:30:40 INFO - Using the following test package requirements: 08:30:40 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:30:40 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:30:40 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:30:40 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:30:40 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'jsshell-mac.zip'], 08:30:40 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:30:40 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:30:40 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:30:40 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:30:40 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:30:40 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:30:40 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:30:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:30:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:30:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:30:40 INFO - Fetch https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:30:42 INFO - Expected file size: 52409469 08:30:42 INFO - Obtained file size: 52409469 08:30:58 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 08:30:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:30:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:30:58 INFO - Fetch https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:31:00 INFO - Expected file size: 52409469 08:31:00 INFO - Obtained file size: 52409469 08:31:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:31:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:31:15 INFO - Fetch https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:31:15 INFO - Expected file size: 14404171 08:31:15 INFO - Obtained file size: 14404171 08:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:16 INFO - trying https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:31:16 INFO - Downloading https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/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 08:31:18 INFO - Downloaded 75435752 bytes. 08:31:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:31:18 INFO - mkdir: /builds/slave/test/properties 08:31:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:31:18 INFO - Writing to file /builds/slave/test/properties/build_url 08:31:18 INFO - Contents: 08:31:18 INFO - build_url:https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:31:19 INFO - Running post-action listener: _resource_record_post_action 08:31:19 INFO - Running post-action listener: set_extra_try_arguments 08:31:19 INFO - [mozharness: 2017-03-23 15:31:19.009675Z] Finished download-and-extract step (success) 08:31:19 INFO - [mozharness: 2017-03-23 15:31:19.009807Z] Running populate-webroot step. 08:31:19 INFO - Running pre-action listener: _resource_record_pre_action 08:31:19 INFO - Running main action method: populate_webroot 08:31:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:19 INFO - '--tppagecycles', 08:31:19 INFO - '7'], 08:31:19 INFO - 'tests': ['tsvgm']}}, 08:31:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:31:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:31:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:31:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['damp', 'tps']}, 08:31:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'tests': ['damp', 'tps']}, 08:31:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['dromaeo_dom']}, 08:31:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['basic_compositor_video']}, 08:31:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:31:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'other-e10s': {'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['a11yr', 08:31:19 INFO - 'ts_paint', 08:31:19 INFO - 'tpaint', 08:31:19 INFO - 'sessionrestore', 08:31:19 INFO - 'sessionrestore_no_auto_restore', 08:31:19 INFO - 'tabpaint']}, 08:31:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['tsvgx', 08:31:19 INFO - 'tsvgr_opacity', 08:31:19 INFO - 'tart', 08:31:19 INFO - 'tscrollx', 08:31:19 INFO - 'cart', 08:31:19 INFO - 'tsvg_static']}, 08:31:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:31:19 INFO - 'tsvgr_opacity', 08:31:19 INFO - 'tart', 08:31:19 INFO - 'tscrollx', 08:31:19 INFO - 'cart', 08:31:19 INFO - 'tsvg_static']}, 08:31:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'talos_options': ['--disable-e10s'], 08:31:19 INFO - 'tests': ['tp5o']}, 08:31:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:31:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'talos_options': ['--disable-e10s', 08:31:19 INFO - '--xperf_path', 08:31:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:19 INFO - 'tests': ['tp5n']}, 08:31:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:31:19 INFO - 'talos_options': ['--xperf_path', 08:31:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:19 INFO - 'tests': ['tp5n']}}, 08:31:19 INFO - 'talos.zip': {'path': '', 08:31:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:19 INFO - Running post-action listener: _resource_record_post_action 08:31:19 INFO - [mozharness: 2017-03-23 15:31:19.028491Z] Finished populate-webroot step (success) 08:31:19 INFO - [mozharness: 2017-03-23 15:31:19.028632Z] Running create-virtualenv step. 08:31:19 INFO - Running pre-action listener: _resource_record_pre_action 08:31:19 INFO - Running main action method: create_virtualenv 08:31:19 INFO - Creating virtualenv /builds/slave/test/build/venv 08:31:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:31:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:31:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:19 INFO - Using real prefix '/tools/python27' 08:31:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:31:19 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:22 INFO - Installing pip.................done. 08:31:22 INFO - Return code: 0 08:31:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:31:23 INFO - Reading from file tmpfile_stdout 08:31:23 INFO - Output received: 08:31:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:31:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:23 INFO - 'HOME': '/Users/cltbld', 08:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:23 INFO - 'LOGNAME': 'cltbld', 08:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:23 INFO - 'MOZ_NO_REMOTE': '1', 08:31:23 INFO - 'NO_EM_RESTART': '1', 08:31:23 INFO - 'PAGER': '/bin/cat', 08:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:23 INFO - 'PWD': '/builds/slave/test', 08:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:23 INFO - 'SHELL': '/bin/bash', 08:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:23 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:23 INFO - 'USER': 'cltbld', 08:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:23 INFO - 'XPC_FLAGS': '0x0', 08:31:23 INFO - 'XPC_SERVICE_NAME': '0', 08:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:31:23 INFO - Cleaning up... 08:31:23 INFO - Return code: 0 08:31:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:23 INFO - 'HOME': '/Users/cltbld', 08:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:23 INFO - 'LOGNAME': 'cltbld', 08:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:23 INFO - 'MOZ_NO_REMOTE': '1', 08:31:23 INFO - 'NO_EM_RESTART': '1', 08:31:23 INFO - 'PAGER': '/bin/cat', 08:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:23 INFO - 'PWD': '/builds/slave/test', 08:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:23 INFO - 'SHELL': '/bin/bash', 08:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:23 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:23 INFO - 'USER': 'cltbld', 08:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:23 INFO - 'XPC_FLAGS': '0x0', 08:31:23 INFO - 'XPC_SERVICE_NAME': '0', 08:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:23 INFO - Downloading/unpacking psutil>=3.1.1 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:31:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:27 INFO - Installing collected packages: psutil 08:31:27 INFO - Running setup.py install for psutil 08:31:27 INFO - building 'psutil._psutil_osx' extension 08:31:27 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 08:31:27 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 08:31:27 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 08:31:27 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 08:31:27 INFO - building 'psutil._psutil_posix' extension 08:31:27 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 08:31:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:31:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:31:28 INFO - ^ 08:31:28 INFO - 1 warning generated. 08:31:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:31:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:28 INFO - Successfully installed psutil 08:31:28 INFO - Cleaning up... 08:31:28 INFO - Return code: 0 08:31:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:28 INFO - 'HOME': '/Users/cltbld', 08:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:28 INFO - 'LOGNAME': 'cltbld', 08:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:28 INFO - 'MOZ_NO_REMOTE': '1', 08:31:28 INFO - 'NO_EM_RESTART': '1', 08:31:28 INFO - 'PAGER': '/bin/cat', 08:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:28 INFO - 'PWD': '/builds/slave/test', 08:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:28 INFO - 'SHELL': '/bin/bash', 08:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:28 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:28 INFO - 'USER': 'cltbld', 08:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:28 INFO - 'XPC_FLAGS': '0x0', 08:31:28 INFO - 'XPC_SERVICE_NAME': '0', 08:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:31:28 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 08:31:28 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 08:31:28 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 08:31:28 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 08:31:28 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 08:31:28 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 08:31:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:31:31 INFO - Installing collected packages: mozsystemmonitor 08:31:31 INFO - Running setup.py install for mozsystemmonitor 08:31:31 INFO - Successfully installed mozsystemmonitor 08:31:31 INFO - Cleaning up... 08:31:31 INFO - Return code: 0 08:31:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:32 INFO - 'HOME': '/Users/cltbld', 08:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:32 INFO - 'LOGNAME': 'cltbld', 08:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:32 INFO - 'MOZ_NO_REMOTE': '1', 08:31:32 INFO - 'NO_EM_RESTART': '1', 08:31:32 INFO - 'PAGER': '/bin/cat', 08:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:32 INFO - 'PWD': '/builds/slave/test', 08:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:32 INFO - 'SHELL': '/bin/bash', 08:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:32 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:32 INFO - 'USER': 'cltbld', 08:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:32 INFO - 'XPC_FLAGS': '0x0', 08:31:32 INFO - 'XPC_SERVICE_NAME': '0', 08:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:31:35 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 08:31:35 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 08:31:35 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 08:31:35 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 08:31:35 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 08:31:35 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 08:31:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:35 INFO - warning: no files found matching '*.txt' 08:31:35 INFO - no previously-included directories found matching 'build' 08:31:35 INFO - no previously-included directories found matching 'dist' 08:31:35 INFO - no previously-included directories found matching '.git*' 08:31:35 INFO - Installing collected packages: jsonschema, functools32 08:31:36 INFO - Running setup.py install for functools32 08:31:36 INFO - warning: no files found matching '*.txt' 08:31:36 INFO - no previously-included directories found matching 'build' 08:31:36 INFO - no previously-included directories found matching 'dist' 08:31:36 INFO - no previously-included directories found matching '.git*' 08:31:36 INFO - Successfully installed jsonschema functools32 08:31:36 INFO - Cleaning up... 08:31:36 INFO - Return code: 0 08:31:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:36 INFO - 'HOME': '/Users/cltbld', 08:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:36 INFO - 'LOGNAME': 'cltbld', 08:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:36 INFO - 'MOZ_NO_REMOTE': '1', 08:31:36 INFO - 'NO_EM_RESTART': '1', 08:31:36 INFO - 'PAGER': '/bin/cat', 08:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:36 INFO - 'PWD': '/builds/slave/test', 08:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:36 INFO - 'SHELL': '/bin/bash', 08:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:36 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:36 INFO - 'USER': 'cltbld', 08:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:36 INFO - 'XPC_FLAGS': '0x0', 08:31:36 INFO - 'XPC_SERVICE_NAME': '0', 08:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:31:36 INFO - Cleaning up... 08:31:36 INFO - Return code: 0 08:31:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:36 INFO - 'HOME': '/Users/cltbld', 08:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:36 INFO - 'LOGNAME': 'cltbld', 08:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:36 INFO - 'MOZ_NO_REMOTE': '1', 08:31:36 INFO - 'NO_EM_RESTART': '1', 08:31:36 INFO - 'PAGER': '/bin/cat', 08:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:36 INFO - 'PWD': '/builds/slave/test', 08:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:36 INFO - 'SHELL': '/bin/bash', 08:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:36 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:36 INFO - 'USER': 'cltbld', 08:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:36 INFO - 'XPC_FLAGS': '0x0', 08:31:36 INFO - 'XPC_SERVICE_NAME': '0', 08:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:40 INFO - Downloading docopt-0.6.1.tar.gz 08:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:40 INFO - Running setup.py install for blobuploader 08:31:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:40 INFO - Running setup.py install for requests 08:31:41 INFO - Running setup.py install for docopt 08:31:41 INFO - Successfully installed blobuploader requests docopt 08:31:41 INFO - Cleaning up... 08:31:41 INFO - Return code: 0 08:31:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:41 INFO - 'HOME': '/Users/cltbld', 08:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:41 INFO - 'LOGNAME': 'cltbld', 08:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:41 INFO - 'MOZ_NO_REMOTE': '1', 08:31:41 INFO - 'NO_EM_RESTART': '1', 08:31:41 INFO - 'PAGER': '/bin/cat', 08:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:41 INFO - 'PWD': '/builds/slave/test', 08:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:41 INFO - 'SHELL': '/bin/bash', 08:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:41 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:41 INFO - 'USER': 'cltbld', 08:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:41 INFO - 'XPC_FLAGS': '0x0', 08:31:41 INFO - 'XPC_SERVICE_NAME': '0', 08:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:41 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-aP5_wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-xcrAp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-lM5PFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-aEqs3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-_3TPOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-aP1z7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-vFLKIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-2nZK0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-a56D9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-oD49oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:42 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-82pzyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-PBATPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-Iw5fbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-X8safo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-wokpFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-LqmPLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:43 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-ivzXqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:43 INFO - Running setup.py install for manifestparser 08:31:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:43 INFO - Running setup.py install for mozcrash 08:31:43 INFO - Running setup.py install for mozdebug 08:31:44 INFO - Running setup.py install for mozdevice 08:31:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Running setup.py install for mozfile 08:31:44 INFO - Running setup.py install for mozhttpd 08:31:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Running setup.py install for mozinfo 08:31:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Running setup.py install for mozInstall 08:31:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:44 INFO - Running setup.py install for mozleak 08:31:44 INFO - Running setup.py install for mozlog 08:31:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Running setup.py install for moznetwork 08:31:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Running setup.py install for mozprocess 08:31:45 INFO - Running setup.py install for mozprofile 08:31:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Running setup.py install for mozrunner 08:31:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:45 INFO - Running setup.py install for mozscreenshot 08:31:45 INFO - Running setup.py install for moztest 08:31:46 INFO - Running setup.py install for mozversion 08:31:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:46 INFO - Cleaning up... 08:31:46 INFO - Return code: 0 08:31:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:46 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 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:46 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 08:31:46 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 08:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:46 INFO - 'HOME': '/Users/cltbld', 08:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:46 INFO - 'LOGNAME': 'cltbld', 08:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:46 INFO - 'MOZ_NO_REMOTE': '1', 08:31:46 INFO - 'NO_EM_RESTART': '1', 08:31:46 INFO - 'PAGER': '/bin/cat', 08:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:46 INFO - 'PWD': '/builds/slave/test', 08:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:46 INFO - 'SHELL': '/bin/bash', 08:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:46 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:46 INFO - 'USER': 'cltbld', 08:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:46 INFO - 'XPC_FLAGS': '0x0', 08:31:46 INFO - 'XPC_SERVICE_NAME': '0', 08:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:46 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-BpkUCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:46 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-PACShY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:46 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-5motkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:46 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-xnIjJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:46 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)) 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:46 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-rxcBbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:47 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)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-wFqQle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:47 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)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-09RzQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:47 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)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-kfwxhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-YZuTM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-UfG7qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-vLKpmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-FnZ9Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:47 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)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-jL4XHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-O_K13n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:47 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-D0TwX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:48 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)) 08:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:48 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-cUbqxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:48 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)) 08:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:48 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-eLL_7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:48 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)) 08:31:48 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)) 08:31:48 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)) 08:31:48 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)) 08:31:48 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)) 08:31:48 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)) 08:31:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:48 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 08:31:48 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 08:31:48 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 08:31:48 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 08:31:48 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 08:31:48 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 08:31:50 INFO - Downloading blessings-1.6.tar.gz 08:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:51 INFO - Installing collected packages: blessings 08:31:51 INFO - Running setup.py install for blessings 08:31:51 INFO - Successfully installed blessings 08:31:51 INFO - Cleaning up... 08:31:51 INFO - Return code: 0 08:31:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:51 INFO - Reading from file tmpfile_stdout 08:31:51 INFO - Current package versions: 08:31:51 INFO - blessings == 1.6 08:31:51 INFO - blobuploader == 1.2.4 08:31:51 INFO - docopt == 0.6.1 08:31:51 INFO - functools32 == 3.2.3-2 08:31:51 INFO - jsonschema == 2.5.1 08:31:51 INFO - manifestparser == 1.1 08:31:51 INFO - mozInstall == 1.12 08:31:51 INFO - mozcrash == 1.0 08:31:51 INFO - mozdebug == 0.1 08:31:51 INFO - mozdevice == 0.49 08:31:51 INFO - mozfile == 1.2 08:31:51 INFO - mozhttpd == 0.7 08:31:51 INFO - mozinfo == 0.9 08:31:51 INFO - mozleak == 0.1 08:31:51 INFO - mozlog == 3.4 08:31:51 INFO - moznetwork == 0.27 08:31:51 INFO - mozprocess == 0.25 08:31:51 INFO - mozprofile == 0.28 08:31:51 INFO - mozrunner == 6.13 08:31:51 INFO - mozscreenshot == 0.1 08:31:51 INFO - mozsystemmonitor == 0.3 08:31:51 INFO - moztest == 0.8 08:31:51 INFO - mozversion == 1.4 08:31:51 INFO - psutil == 3.1.1 08:31:51 INFO - requests == 1.2.3 08:31:51 INFO - wsgiref == 0.1.2 08:31:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:51 INFO - 'HOME': '/Users/cltbld', 08:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:51 INFO - 'LOGNAME': 'cltbld', 08:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:51 INFO - 'MOZ_NO_REMOTE': '1', 08:31:51 INFO - 'NO_EM_RESTART': '1', 08:31:51 INFO - 'PAGER': '/bin/cat', 08:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:51 INFO - 'PWD': '/builds/slave/test', 08:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:51 INFO - 'SHELL': '/bin/bash', 08:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:51 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:51 INFO - 'USER': 'cltbld', 08:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:51 INFO - 'XPC_FLAGS': '0x0', 08:31:51 INFO - 'XPC_SERVICE_NAME': '0', 08:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:31:51 INFO - Cleaning up... 08:31:51 INFO - Return code: 0 08:31:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c813b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c816768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb732c6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, '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.sFYnjus2RE/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.kOQacvlmbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:31:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:51 INFO - 'HOME': '/Users/cltbld', 08:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:51 INFO - 'LOGNAME': 'cltbld', 08:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:51 INFO - 'MOZ_NO_REMOTE': '1', 08:31:51 INFO - 'NO_EM_RESTART': '1', 08:31:51 INFO - 'PAGER': '/bin/cat', 08:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:51 INFO - 'PWD': '/builds/slave/test', 08:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:51 INFO - 'SHELL': '/bin/bash', 08:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:31:51 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:51 INFO - 'USER': 'cltbld', 08:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:51 INFO - 'XPC_FLAGS': '0x0', 08:31:51 INFO - 'XPC_SERVICE_NAME': '0', 08:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:52 INFO - Cleaning up... 08:31:52 INFO - Return code: 0 08:31:52 INFO - Running post-action listener: _resource_record_post_action 08:31:52 INFO - Running post-action listener: _start_resource_monitoring 08:31:52 INFO - Starting resource monitoring. 08:31:52 INFO - [mozharness: 2017-03-23 15:31:52.131342Z] Finished create-virtualenv step (success) 08:31:52 INFO - [mozharness: 2017-03-23 15:31:52.132301Z] Running install step. 08:31:52 INFO - Running pre-action listener: _resource_record_pre_action 08:31:52 INFO - Running main action method: install 08:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:52 INFO - Reading from file tmpfile_stdout 08:31:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:52 INFO - Reading from file tmpfile_stdout 08:31:52 INFO - Output received: 08:31:52 INFO - Usage: mozinstall [options] installer 08:31:52 INFO - Options: 08:31:52 INFO - -h, --help show this help message and exit 08:31:52 INFO - -d DEST, --destination=DEST 08:31:52 INFO - Directory to install application into. [default: 08:31:52 INFO - "/builds/slave/test"] 08:31:52 INFO - --app=APP Application being installed. [default: firefox] 08:31:52 INFO - mkdir: /builds/slave/test/build/application 08:31:52 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'] 08:31:52 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 08:32:13 INFO - Reading from file tmpfile_stdout 08:32:13 INFO - Output received: 08:32:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:32:13 INFO - Running post-action listener: _resource_record_post_action 08:32:13 INFO - [mozharness: 2017-03-23 15:32:13.093471Z] Finished install step (success) 08:32:13 INFO - [mozharness: 2017-03-23 15:32:13.093594Z] Running run-tests step. 08:32:13 INFO - Running pre-action listener: _resource_record_pre_action 08:32:13 INFO - Running main action method: run_tests 08:32:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:32:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:32:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:32:13 INFO - 2017-03-23 08:32:13.107 screenresolution[1626:7507] starting screenresolution argv=screenresolution get 08:32:13 INFO - 2017-03-23 08:32:13.110 screenresolution[1626:7507] Display 0: 1600x1200x32@60 08:32:13 INFO - 2017-03-23 08:32:13.116 screenresolution[1627:7510] starting screenresolution argv=screenresolution list 08:32:13 INFO - Available Modes on Display 0 08:32:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:32:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:32:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:32:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:32:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:32:13 INFO - Intel Iris: 08:32:13 INFO - Chipset Model: Intel Iris 08:32:13 INFO - Type: GPU 08:32:13 INFO - Bus: Built-In 08:32:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:32:13 INFO - Vendor: Intel (0x8086) 08:32:13 INFO - Device ID: 0x0a2e 08:32:13 INFO - Revision ID: 0x0009 08:32:13 INFO - Displays: 08:32:13 INFO - Display: 08:32:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:32:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:32:13 INFO - Display Serial Number: 1600x1200 60 08:32:13 INFO - Main Display: Yes 08:32:13 INFO - Mirror: Off 08:32:13 INFO - Online: Yes 08:32:13 INFO - Rotation: Supported 08:32:13 INFO - Return code: 0 08:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:13 INFO - Python 2.7.3 08:32:13 INFO - Return code: 0 08:32:13 INFO - grabbing minidump binary from tooltool 08:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:13 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 0x10c5f1d30>, '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 0x10c518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5e6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:13 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 08:32:13 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 08:32:13 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 08:32:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:13 INFO - Return code: 0 08:32:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:32:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:13 INFO - ENV: RUST_BACKTRACE is now 1 08:32:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:32:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:13 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--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 08:32:13 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0378 --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 08:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFYnjus2RE/Render', 08:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:13 INFO - 'HOME': '/Users/cltbld', 08:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:13 INFO - 'LOGNAME': 'cltbld', 08:32:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:13 INFO - 'MOZ_NO_REMOTE': '1', 08:32:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:13 INFO - 'NO_EM_RESTART': '1', 08:32:13 INFO - 'PAGER': '/bin/cat', 08:32:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:13 INFO - 'PWD': '/builds/slave/test', 08:32:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:13 INFO - 'RUST_BACKTRACE': '1', 08:32:13 INFO - 'SHELL': '/bin/bash', 08:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kOQacvlmbB/Listeners', 08:32:13 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:13 INFO - 'USER': 'cltbld', 08:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:13 INFO - 'XPC_FLAGS': '0x0', 08:32:13 INFO - 'XPC_SERVICE_NAME': '0', 08:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:13 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--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 08:32:13 INFO - mozversion application_buildid: 20170323054323 08:32:13 INFO - mozversion application_changeset: 89b93d310da5bc7f86ebcdb8fe580d47a610757a 08:32:13 INFO - mozversion application_display_name: Nightly 08:32:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:13 INFO - mozversion application_name: Firefox 08:32:13 INFO - mozversion application_remotingname: firefox 08:32:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:32:13 INFO - mozversion application_vendor: Mozilla 08:32:13 INFO - mozversion application_version: 55.0a1 08:32:13 INFO - mozversion platform_buildid: 20170323054323 08:32:13 INFO - mozversion platform_changeset: 89b93d310da5bc7f86ebcdb8fe580d47a610757a 08:32:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:32:13 INFO - mozversion platform_version: 55.0a1 08:32:13 INFO - using testdate: 1490283133 08:32:13 INFO - actual date: 1490283133 08:32:13 INFO - starting webserver on 'localhost:49248' 08:32:13 INFO - SUITE-START | Running 2 tests 08:32:13 INFO - TEST-START | dromaeo_css 08:32:13 INFO - operating with platform_type : mac_ 08:32:13 INFO - Initialising browser for dromaeo_css test... 08:32:13 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmplxNeiG/profile http://localhost:49248/getInfo.html) 08:32:16 INFO - PID 1637 | 2017-03-23 08:32:16.541 plugin-container[1639:7658] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 08:32:16 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:16 INFO - PID 1637 | 2017-03-23 08:32:16.546 plugin-container[1639:7658] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 08:32:16 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:16 INFO - PID 1637 | 2017-03-23 08:32:16.546 plugin-container[1639:7658] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:32:16 INFO - PID 1637 | __metrics Screen width/height:1600/1200 08:32:16 INFO - PID 1637 | colorDepth:24 08:32:16 INFO - PID 1637 | Browser inner width/height: 1024/689 08:32:16 INFO - PID 1637 | __metrics 08:32:16 ERROR - PID 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3388: TypeError: this.tabs is undefined 08:32:17 INFO - TEST-INFO | 1637: exit 0 08:32:17 INFO - Browser initialized. 08:32:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:17 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmplxNeiG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:20 INFO - PID 1640 | 2017-03-23 08:32:20.059 plugin-container[1641:7783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 08:32:20 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:20 INFO - PID 1640 | 2017-03-23 08:32:20.061 plugin-container[1641:7783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 08:32:20 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:32:20 INFO - PID 1640 | 2017-03-23 08:32:20.061 plugin-container[1641:7783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:32:29 INFO - PID 1640 | RSS: Main: 228241408 08:32:29 INFO - PID 1640 | 08:35:33 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 08:35:35 INFO - PID 1640 | RSS: Main: 251449344 08:35:35 INFO - PID 1640 | 08:37:17 INFO - PID 1640 | ************************* 08:37:17 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 08:37:17 INFO - PID 1640 | 08:37:17 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:37:17 INFO - PID 1640 | Full stack: 08:37:17 INFO - PID 1640 | ************************* 08:38:38 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 08:38:38 INFO - PID 1640 | RSS: Main: 259960832 08:38:38 INFO - PID 1640 | 08:41:40 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 08:41:41 INFO - PID 1640 | RSS: Main: 271212544 08:41:41 INFO - PID 1640 | 08:44:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 08:44:43 INFO - PID 1640 | RSS: Main: 264069120 08:44:43 INFO - PID 1640 | 08:47:46 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 08:47:47 INFO - PID 1640 | RSS: Main: 274247680 08:47:47 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 08:50:50 INFO - PID 1640 | RSS: Main: 267956224 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | __start_tp_report 08:50:50 INFO - PID 1640 | _x_x_mozilla_page_load 08:50:50 INFO - PID 1640 | _x_x_mozilla_page_load_details 08:50:50 INFO - PID 1640 | |i|pagename|runs| 08:50:50 INFO - PID 1640 | |0;dojo.html;2771.847548384839;3544.4328907374825;3544.6100928897818;3546.680798728114;3607.9458808117884;6118.253719382601;6193.566450348471;6220.564560480762;6235.875282494353;6240.3759624037575;6110.649890384128;6169.660668663222;6216.658083805389;6255.687215639211;6318.368163183691;5668.461496157858;5705.830304787509;5777.046787280094;5822.301323841124;5849.853753656172;4997.652802553757;5606.21512988182;5779.884402311951;5787.739551720183;5820.330661973863;5500.339959204881;5589.217509548666;5738.225339579187;5743.798967036134;5765.308163020443;5587.8882422351735;5640.74616642248;5662.150677398382;5682.715864206773;5730.598858079936;5510.090835012218;5594.608377413582;5603.355614104348;5637.210790489335;5644.689542075184;6064.332923378425;6090.66501342426;6258.718357673915;6298.779542715983;6314.084457753646;6484.837879053014;6579.342065793382;6621.73513059477;6634.270230274666;6671.499637527205;6741.629210393427;6765.898511522331;6782.694778734969;6786.898196527056;6788.966055169742;6296.87406251877;6298.559100862987;6317.273513545909;6338.461230795388;6341.778037768702;6630.900536491957;6718.428933540704;6801.489783842115;6878.8624227515165;6942.097527321416;5556.472135147154;5859.740155866509;6325.240971083499;6380.521460890452;6403.711832967528;6522.771702990372;6642.402183803384;6656.467482601381;6746.359095885883;6760.425363844129;6662.367075127952;6666.333366663295;6674.16572928384;6679.365460281266;6685.298043705341;5577.609567330329;6435.871282574416;6478.449331806755;6489.1888513935755;6689;4668.346431499593;5277.656952298088;5295.7087360195555;5346.572274218053;5354.089804733205;4719.905601888012;4753.096911586787;4761.40482439695;4808.8797780055775;4809.2064809306075;6272.090546870678;6289.7484100635465;6329.303776584664;6370.299267080617;6374.968125159345;4996.575291100297;6531.575447595891;6629.834254143685;6748.460123190232;6835.624040677811;6345.841353966188;6358.936410635927;6382.712777924911;6384.3934826191435;6390.073439351361;5985.670788106696;6037.879242415127;6038.969805150946;6044.818655440344;6078.787242446493;6522.380373864476;6628.27089020217;6794.422474089758;6856.657167141623;7014.088168538057;6741;6819.965900170468;6824.556403833735;6840.623765692934;6843.794686159423;5743.56923230751;6890;6898.551594146365;6901.62041087745;6933.306669333027;5898.64331203817;6259.280182778856;6374.07575901501;6431;6612.8346791330605;6724.327567243236;6725.39471447572;6788.898166527407;6805.455563554805;6821.658917054227;6538;6540.574862633914;6543.640099794557;6550.7707230246715;6551.475881929529;6267.435930766253;6279.466245369194;6488.480921526361;6547.279799222177;6568.9014664781025;6183.938160618336;6252.812415627538;6358.841028974128;6413.647249401328;6428.164338635947;6528.640924749185;6546.934530654823;6564.081028655896;6568.7372505101175;6609.843277426335;6227.813165605039;6252.968735156295;6301.716422761078;6316.115743795965;6386.105945167587 08:50:50 INFO - PID 1640 | |1;ext.html;727.2000799120967;801.058755961745;821.8109834738008;1252.7932891072974;1262.1291308996797;37515.43726844096;38658.87376194315;38677.61322386775;38686.61313386869;38843.611563884355;38320.61679383205;38615.806920965355;38687.83936481903;38763.612363876426;38860;8067.596620168997;8251.95874020632;8518.87221691675;8727.95636021819;8734.86897696535;8557.272631826303;8643.827123457528;8663.870041949376;8671.523066231355;8688.174623410765;8632.741017769446;8648.481091134521;8716.694915677921;8718.956405217965;8736.388452808305;8667.046624871258;8692.261157801595;8698.999615044288;8774.674711201536;8811;8656.307495400353;8714.869276960788;8742.737717868475;8766.780830479225;8768.430052046597;38035.429468557995;38341;38369.42445863314;38410.03974900622;38642.61357386418;3047.177262139232;3078.291992841637;3079.4610943085095;3085.598872146629;3093.520504321834;27427.588586171023;27812.860935695193;28040;28154.859225703945;28838.423231535253;2725.1960671601923;2736.3569561153113;2737.117102744104;2750.614913912054;2831.164806382122;210616;213399;233831;234350;263221.0516842268;37786.43320350197;46193.61419157364;46442.303365449545;46535.30197047047;46597.53402465932;37309.44035839465;45147;48935.5106448931;48977.75511122493;49005.01989960181;13361.997850161277;13474.86525134736;13484.595462136152;13518.459261629621;13529.052966292462;11042.889571104346;11138.49876755548;11188.888111118948;11194.832077518844;11205.943970280096;11147;13878.097923634932;13907.443702252;13927.442902283998;14006.019578629603;5595.272614560081;5790.826275211751;6079.878402431927;6086.147939288503;6124.050772130327;869.3393021303855;905.7599736069947;950.1543626172712;965.7392504024015;1001.1690297053574;23317.41706457352;24370.268891932916;24698.13556525513;24836.627450588254;24870.626940595906;1058.8200005999;1058.9046985771317;1091.5961094395125;1094.819354806448;1117.1844553475846;10296.794064118676;11769.941150294193;12291.631251062483;12302.876971230353;12453;421531;421573;423127;423713;424114;54587.727061364436;54892.45107548873;54900;55009.1748623771;55011;1465.3113036872653;1492.1121932450392;1506.6158129676862;1510.2750679673818;1510.4939845151778;23038.769612303662;23966.041358345472;25942.481150377265;28080.578791318556;28904.710952891044;53828;54179;54283.45716542784;54321.45678543164;54433;427952;428211;429995;433064.8346758246;433225;26829.865850670623;27402.17793466199;32869.17827054247;34806;35813.1046723834;33122.66877331292;34942.77700280478;39746;40716.79641601773;41269.793651032756;28088.719112809427;29685;29963.400731984802;30057.248568785082;30398.240043999078;28941.85529072341;30006;30175.403577933404;30225.848870755504;30299.242518937204;35066.47400288947;35391;36213.27573448569;36218.81890590636;36348.818255908554;22337;28022.299442513286;29454.558181627723;29693.40613187681;29868.253293666963;22551.32346029622;23550.76449235554;23831.64252536248;24043.759562404153;24055.759442405353 08:50:50 INFO - PID 1640 | |2;jquery.html;414.19852585247565;416.448206126882;421.6479239834738;429.6734481793837;439.5889842996796;19360.419187424366;20601.484962875937;21302.57394852102;21359.932003399826;21508.247211347607;19975.900120499413;21833.67249491255;21891.781082189213;22095;23074.307770766864;6142.877142457137;6344.968275158618;6351.269603995554;6365.52258580606;6368.203974503187;5045;6214;6280.905786413207;6285.842853928642;6341.397567231105;5636.689982051006;6020.825938941908;6259.71831267594;6330.113784070234;6333.145025421555;6103.87792244157;6196.907046394308;6307.053941908727;6366.267879193906;6373.203349581326;5586.553075753971;5597.61413168158;5668.801591944262;5697.1169468732;5714.371419143891;26211.73788262112;27419.725802741916;27691.584626230622;27745.445091098267;27861.860690696416;2025.422754514956;2029.6245194639039;2037.836973042153;2066.545360020808;2081.5004398944297;12170.513179472811;15935.123568203744;16046.598835029217;16317.265723042372;17206.913965430216;1675.6367965475358;1707.9573010674708;1708.7864016997876;1716.1933891071176;1717.2444124585268;139199;145883.2705836464;146036.53963460337;146928;148963;12495.062870284764;12495.625131246077;12506.437210325557;12656.36718164088;12813.679658008437;16201.75697364517;16216.75674864878;16220.918895405683;16248.025118492928;16249.512514624581;6768.390844823989;7047.295270472911;8434.536100514531;8778.990416102102;8813.867791983126;8063.758087257392;8068.9596552016865;8083.312918402002;8187.181281871765;8214.917850821415;7811.4922530035365;7970.290101365564;8019;8097.716579919792;8101.878471822927;2162.88104154273;2179.869207847534;2180.5856887191385;2181.2692747929623;2361.6457531370434;904.7331037344;907.7276816954887;908.1599520443564;946.588234118147;961.5240455974198;19533.511662208275;20068.197272109242;20084.89957550203;20443.08006139727;20595.176192952113;946.4794363100267;964.1033838530234;965.7295957132014;966.6133546581283;990.0742805476903;9003.864942025875;10074;10119.494025298705;10377.11794497461;10486.685399438029;594247.0287648534;602711;604571;627993;651463;116503;122917;138292;149013;153134.23432882765;1113.1317572293785;1194.0447641886456;1203.1157099531838;1229.7601967616229;1229.9200551964093;17680.027598482207;17706.734398984285;17776.555586109935;17824.55438613993;18145.45563633093;40631.593684062784;60944.39055609565;61268;61433.07850382158;62664.48026236881;84371;84971.30057398941;85526;85917.28165436782;87176.56411718155;6692.330766923464;8318.70884519039;8433.578321083798;8500.91499085026;8757.080506546721;12176.634700958986;12919.935400322938;13017.479300827861;13031.47874085064;13436.865631343562;9914.553845076845;11228.831567526331;11467.598634047768;11667.00830429423;11678.357690327113;10384.896151038392;10484.318519296221;10690.465476726287;10763.51564179598;11148.721281968015;11570.247933884271;11927.105467090176;11940.940295298467;11949.581764638197;11967.700807479883;7335.963320183365;8538;8558.67340562189;8573.099824518336;8574.185452382264;11129.944350278196;11408.828867566826;11563.595274165364;11642.76714465689;11735.295882247092 08:50:50 INFO - PID 1640 | |3;mootools.html;1270.911036227464;1426.343881814366;1435.8635929586699;1443.0908527627594;1459.350588987901;10392.428416437093;10725.839112413301;10839.945800270998;10844.96972787586;11012.39431831251;10761.085307748832;10929.344239345644;10945.945270273658;10955.452227388638;10957.671269861898;2439.890204940782;2460.0897667862914;2468.4939587384633;2475.653408522808;2476.2447453526665;2336.745395193573;2393.533261014103;2408.397900524869;2414.4929564791414;2420.177139772477;2410.0480310277517;2435.3789783605243;2458.8770561471943;2461.310832966772;2465.7410971847967;2358.5872472317446;2434.7443518430578;2446.3639453742157;2446.779789818916;2452.2030340139527;2055.969160462594;2147.6134295826746;2335.3344296875307;2401.5557121932497;2454.7545245475308;10865.022148006718;10908.672739817817;10909.672709818638;10915.727106822314;10918;6149.200603921507;6424.486041116699;6441.967790161019;6449.774257900998;6456.225252969627;3875.8255878485534;3924.50943632046;4020.1356708307853;4041.0503531670033;4069.491313585802;954.742219600704;1162.2798258577352;1165.3648761424959;1223.1988047828686;1224.7121926347302;15308.004979676285;15323.080615163126;15430.38278468871;15469.071855688695;15490.690186196212;5284.3658760948165;5333.57331413486;5414.431484694129;5468.28912241406;5519.972400137974;9138.63445462174;11178.105751539857;11354.26197297173;11402.20184587071;11429.828552571718;5921.467067963904;6247.500199983991;6256.499480041586;6261.217347831521;6274.247090349188;4337.522872484024;5140.203268493315;5759.8560035999435;5762.193292938992;5822.505087067647;3705.2219034002624;4130.669546436338;4135.234981528427;4226.556211597799;4319.632831209319;1268.6384380451527;1273.0579371265196;1274.4201388368456;1289.5164313382481;1290.1033781521753;645.4320198225626;731.8536292741367;744.1553836395622;759.0928840036214;776.1191048160379;4857.9028419431415;5752.626079304831;5963.9563076461445;6018.127371531191;6147.508199344042;554.6782865937746;559.9300087489064;576.5442258297832;577.6793879396891;589.5990726306153;2984.328526081614;3555.7510974231554;3684.428913518409;3705.2219034002624;3723.497327860758;13611.659708507168;13779.311034448438;14119.364628591738;14138.929305353407;14190.787138192936;13525.39135738894;13647;13785.103968242032;13822.654433639038;13931.930340348234;8896.955515222511;10054.849177262347;10098.747531311776;10199.18406527476;10252.948735256276;6298.7480500781285;6309.097815005149;6321.715522801393;6328.493720502441;6351.841203969938;11947.402629868646;12322.075844311534;12335.321557314433;12351.69120771988;12388.690282743004;12193.69515762113;12433.440495177558;12465.314407707663;12515.249085054926;12772.297523636289;7756.961215193888;7822.748360262331;8842.204201621886;9020.639174432948;9036.502992335485;10071.295009349275;10106.292559521056;10113.797724045331;10169.135623472088;10175.592976280866;7885.369170466463;7919.762407127795;8383.287420569077;8652.394332396923;8682.175193356621;8343.874841877498;8347.874781878398;8407.831843362976;8465.238128568211;8490.787730306545;6586.736530538725;7145.749898753519;7619.047619047619;7804.882926756217;7809.531428114331;5732.140178973187;5839.036558967892;5917.319508256605;5972.074328479092;5993.820185394445;5907.375471745305;5991.700414979321;6002.039673652194;6006.279246490449;6045.727942242522 08:50:50 INFO - PID 1640 | |4;prototype.html;396.99758110770284;399.9880003599892;400.7815740421468;401.3938952182204;403.541979852867;5718.942810571898;6358.713857876395;6410.935890641093;6479.805605831821;6488.253850807158;5467.753951072202;5846.88306233875;6222.5644204905675;6264.906026409596;6290.665947048061;4897.461279259279;4902.926456103161;4903.362562866841;4916.729579873106;4927.50724927508;4801.255805350159;4935.925961110586;4947.529984651453;4962.379702537183;4964.255361695757;4772.8999240334315;4819.301201325806;4859.173940430118;4860.441049279325;4860.5868501177265;4464.999999999984;4806.711597304173;4857.902841943177;4865;4892.339534162914;3503.5985605757646;4305.9784701076605;4319.179355922396;4320.935185972213;4332.848350307756;5814.534837213013;6059.757609695607;6130.96934515329;6141.631502109888;6150.50795936324;4319.913601727948;5419.430959749209;5448.618596698193;5469.234307196995;5523.337199536081;5948.553858460633;6126.713390188066;6679.7249760153545;6724.293949135319;6735.528513004092;3700.481932529448;3894.240623078501;4445.711028783151;4446.333050042487;4448.266036104039;6753.763618273337;6998.650067496605;7022.52526969337;7134;7135.4648401370105;4138.730982486129;4147.502299724052;4515.2549829277805;4528.0943811237885;4561.338605902126;3823.5985221551337;4624.930626040612;4641.303804429363;4647.86056418308;4649.488556258809;5453.291072160594;5453.809116680897;5454.699991500505;5482.5888058394985;5494.038543254915;5977.850553736104;6055.122007308915;6487.578307410097;6740.258571557125;6745.392914637706;1549.457689808558;1568.9921550392176;1572.1039007765692;1575.102191750714;1584.3857634902504;903.2367649336301;964.7940074906367;969.2391472693902;1026.2457094035879;1031.5203430404897;5020.849374518771;5308.629020571964;6311.179546658905;6318.210223722035;6343.619382837044;5754.971225143848;5954.761809527657;6159.384061593805;6584.703688334036;6920.30796920304;4798.4001999750035;5544.417836127229;6022.855657425075;6217.316384886175;6259.78090766821;3470.288590838872;3612.205314830733;3619.2399596084592;3651.6348365163803;3657.8719744808805;5629;6970.826499676934;7033.366996970297;7122.18094919091;7161.856762864713;5651;6335.904961425582;6792.539603985068;7128.073350464407;7202.71189152428;5486.31421072366;5526.834194974157;5642.576806739593;6782.101011716727;7070.399016083587;6546.443552298108;6895.6552172390175;6965.756198533027;7022.613756243456;7047.577145371294;5670.4689733772475;5757.827265182052;6514.869702605826;6845.247022827385;6856.5200435969;6544.854650436059;6620.933790662032;7197.424206063398;7243.855122897618;7257.745978890714;3719.3119272934596;3859.749116307431;4230.534641189528;4265.104328091012;4552.317152427162;5241.528262456397;5377.489138531833;6309.747610095545;6987.406070484066;7060;5928.9407105928385;6726.02472641474;6747.595144291358;6755;6786.4910131739325;5465;5718.027935250934;6228.346023667631;6739.663016849236;6753.223379311442;6515.706793194223;6577.566300468857;6619.867602647823;6620.470362370903;6634.701438435379;6475.579087359307;6666.633335166613;6761.323867613396;6762.323767623198;6772.7629532968085;6585.440237579859;6587.308332625005;6603.372679595431;6609.372109649768;6625.503087268377 08:50:50 INFO - PID 1640 | |5;yui.html;694.7221111555381;720.2221600671272;724.1021133794094;724.7753196509083;730.8757511223091;12425.068119890999;15712;16021.919890400532;16027.519174424757;16144.919275403621;14832.332545035473;14919.477818276362;14941.477048303312;15024.248787560633;15050.698986020274;877.2587163846539;916.6608354908693;923.6767131503987;924.2328867040374;964.9951750241239;945.1776954049986;948.7533241357228;964.1178321835512;964.6720115160844;967.3712087143343;999.3004896572392;1007.4811472091878;1013.7516308504427;1014.345746993189;1016.8525563793246;1003.4531180506641;1008.5259927833906;1009.6718566465928;1009.808136454075;1012.2408193854609;740.7222291640635;747.4431548496351;749.1534565940507;749.8837680159584;751.70683433461;14878.256087195598;16183.514494565183;16234.837651623451;16597.91701041487;16641.33434662612;14468.927655361762;14576.93797872018;14578.052426592238;14678.633034174125;14701.779473307908;11636.825447618292;11755.235909665844;11950.581729639507;12028.218165819193;12062.819057714141;2990.132861470171;3019.4564978303897;3047.5733397324393;3104.8758049677986;3105.968940310613;97405;97926;98116;102542.48728756382;103975.96024039738;9054.954725226331;9545.809083818423;10191;10516.68449946503;11692.47383867728;21984;23983.441076329982;24166.395840103785;24188.274351769476;24326.635100473508;7841.137474877759;7878.054633443909;7988.360931125496;8005.159458256916;8071.542995164312;4065.8780236592947;4168.8332466701595;4175.060611362419;4182.862475861411;4204.915901681949;3494.4583589543663;3521.6830485255937;4211.7051806373875;4237.87286381409;4240.448741663564;751.1075611825222;842.7485184501011;848.8047749017637;850.6639877248452;857.6183598298821;536.3108405698658;542.7204989430446;549.7526113249054;561.8904313658879;570.6034306157181;9967.950160249298;10198.439085850201;10273.691789246335;10277.486125693686;10326.535305911251;525.4850246758199;550.5664960872872;566.577899464893;576.974036168365;594.6016169166669;4543.704659197141;4745.169595320874;5120.61595380348;5198.870028249324;5223.059849227099;80766.19233807705;94030.0596994035;95601.5219923896;96736;97668.51165744125;80991;82420.58789706013;83747.32505349981;86257.5687121573;88339;73727.63136184392;73733.631331843;74408.62795685987;75551;80617.19382806332;10542.209334300112;10794.56821727122;10968.780624387628;11044.502997364978;11184.496697648789;31556.36887262288;38149.4277585842;39685.801570991964;39687.40468892911;39895;48508.51491485181;48703.756481217366;48831.535053948435;48875;49140.75429622972;13169.407376668265;13220.735585288432;13224;13272.336383180995;13660.248686322348;11387.658370248906;12095.032397408011;14014.36935337892;14398.280085995868;14735.92632036833;10700.571977120828;11464.713382165513;11890.881091188978;13630.977676674487;13836.962227832752;13296.601101966957;13435.932820336227;13540.729185416434;13612.455501780214;14044.016918815585;12908.935455322664;12939.611811645289;13084.28036458004;13495.59513214605;13663.925616736155;8999;9228.584713687766;9264.258859291105;9387.953060234928;9415.62337506492;7686.538807671557;8718.869216961624;9646;10679.839802403125;10880.67357979262 08:50:50 INFO - PID 1640 | __end_tp_report 08:50:50 INFO - PID 1640 | __start_cc_report 08:50:50 INFO - PID 1640 | _x_x_mozilla_cycle_collect,428 08:50:50 INFO - PID 1640 | __end_cc_report 08:50:50 INFO - PID 1640 | __startTimestamp1490284250739__endTimestamp 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | ------- Summary: start ------- 08:50:50 INFO - PID 1640 | Number of tests: 6 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6155.24 Median:6338.46 stddev:713.94 (11.3%) stddev-sans-first:664.97 08:50:50 INFO - PID 1640 | Values: 2771.8 3544.4 3544.6 3546.7 3607.9 6118.3 6193.6 6220.6 6235.9 6240.4 6110.6 6169.7 6216.7 6255.7 6318.4 5668.5 5705.8 5777.0 5822.3 5849.9 4997.7 5606.2 5779.9 5787.7 5820.3 5500.3 5589.2 5738.2 5743.8 5765.3 5587.9 5640.7 5662.2 5682.7 5730.6 5510.1 5594.6 5603.4 5637.2 5644.7 6064.3 6090.7 6258.7 6298.8 6314.1 6484.8 6579.3 6621.7 6634.3 6671.5 6741.6 6765.9 6782.7 6786.9 6789.0 6296.9 6298.6 6317.3 6338.5 6341.8 6630.9 6718.4 6801.5 6878.9 6942.1 5556.5 5859.7 6325.2 6380.5 6403.7 6522.8 6642.4 6656.5 6746.4 6760.4 6662.4 6666.3 6674.2 6679.4 6685.3 5577.6 6435.9 6478.4 6489.2 6689.0 4668.3 5277.7 5295.7 5346.6 5354.1 4719.9 4753.1 4761.4 4808.9 4809.2 6272.1 6289.7 6329.3 6370.3 6375.0 4996.6 6531.6 6629.8 6748.5 6835.6 6345.8 6358.9 6382.7 6384.4 6390.1 5985.7 6037.9 6039.0 6044.8 6078.8 6522.4 6628.3 6794.4 6856.7 7014.1 6741.0 6820.0 6824.6 6840.6 6843.8 5743.6 6890.0 6898.6 6901.6 6933.3 5898.6 6259.3 6374.1 6431.0 6612.8 6724.3 6725.4 6788.9 6805.5 6821.7 6538.0 6540.6 6543.6 6550.8 6551.5 6267.4 6279.5 6488.5 6547.3 6568.9 6183.9 6252.8 6358.8 6413.6 6428.2 6528.6 6546.9 6564.1 6568.7 6609.8 6227.8 6253.0 6301.7 6316.1 6386.1 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:50104.05 Median:35602.05 stddev:99158.15 (278.5%) stddev-sans-first:99367.01 08:50:50 INFO - PID 1640 | Values: 727.2 801.1 821.8 1252.8 1262.1 37515.4 38658.9 38677.6 38686.6 38843.6 38320.6 38615.8 38687.8 38763.6 38860.0 8067.6 8252.0 8518.9 8728.0 8734.9 8557.3 8643.8 8663.9 8671.5 8688.2 8632.7 8648.5 8716.7 8719.0 8736.4 8667.0 8692.3 8699.0 8774.7 8811.0 8656.3 8714.9 8742.7 8766.8 8768.4 38035.4 38341.0 38369.4 38410.0 38642.6 3047.2 3078.3 3079.5 3085.6 3093.5 27427.6 27812.9 28040.0 28154.9 28838.4 2725.2 2736.4 2737.1 2750.6 2831.2 210616.0 213399.0 233831.0 234350.0 263221.1 37786.4 46193.6 46442.3 46535.3 46597.5 37309.4 45147.0 48935.5 48977.8 49005.0 13362.0 13474.9 13484.6 13518.5 13529.1 11042.9 11138.5 11188.9 11194.8 11205.9 11147.0 13878.1 13907.4 13927.4 14006.0 5595.3 5790.8 6079.9 6086.1 6124.1 869.3 905.8 950.2 965.7 1001.2 23317.4 24370.3 24698.1 24836.6 24870.6 1058.8 1058.9 1091.6 1094.8 1117.2 10296.8 11769.9 12291.6 12302.9 12453.0 421531.0 421573.0 423127.0 423713.0 424114.0 54587.7 54892.5 54900.0 55009.2 55011.0 1465.3 1492.1 1506.6 1510.3 1510.5 23038.8 23966.0 25942.5 28080.6 28904.7 53828.0 54179.0 54283.5 54321.5 54433.0 427952.0 428211.0 429995.0 433064.8 433225.0 26829.9 27402.2 32869.2 34806.0 35813.1 33122.7 34942.8 39746.0 40716.8 41269.8 28088.7 29685.0 29963.4 30057.2 30398.2 28941.9 30006.0 30175.4 30225.8 30299.2 35066.5 35391.0 36213.3 36218.8 36348.8 22337.0 28022.3 29454.6 29693.4 29868.3 22551.3 23550.8 23831.6 24043.8 24055.8 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:37262.78 Median:22584.65 stddev:103708.63 (459.2%) stddev-sans-first:103962.65 08:50:50 INFO - PID 1640 | Values: 414.2 416.4 421.6 429.7 439.6 19360.4 20601.5 21302.6 21359.9 21508.2 19975.9 21833.7 21891.8 22095.0 23074.3 6142.9 6345.0 6351.3 6365.5 6368.2 5045.0 6214.0 6280.9 6285.8 6341.4 5636.7 6020.8 6259.7 6330.1 6333.1 6103.9 6196.9 6307.1 6366.3 6373.2 5586.6 5597.6 5668.8 5697.1 5714.4 26211.7 27419.7 27691.6 27745.4 27861.9 2025.4 2029.6 2037.8 2066.5 2081.5 12170.5 15935.1 16046.6 16317.3 17206.9 1675.6 1708.0 1708.8 1716.2 1717.2 139199.0 145883.3 146036.5 146928.0 148963.0 12495.1 12495.6 12506.4 12656.4 12813.7 16201.8 16216.8 16220.9 16248.0 16249.5 6768.4 7047.3 8434.5 8779.0 8813.9 8063.8 8069.0 8083.3 8187.2 8214.9 7811.5 7970.3 8019.0 8097.7 8101.9 2162.9 2179.9 2180.6 2181.3 2361.6 904.7 907.7 908.2 946.6 961.5 19533.5 20068.2 20084.9 20443.1 20595.2 946.5 964.1 965.7 966.6 990.1 9003.9 10074.0 10119.5 10377.1 10486.7 594247.0 602711.0 604571.0 627993.0 651463.0 116503.0 122917.0 138292.0 149013.0 153134.2 1113.1 1194.0 1203.1 1229.8 1229.9 17680.0 17706.7 17776.6 17824.6 18145.5 40631.6 60944.4 61268.0 61433.1 62664.5 84371.0 84971.3 85526.0 85917.3 87176.6 6692.3 8318.7 8433.6 8500.9 8757.1 12176.6 12919.9 13017.5 13031.5 13436.9 9914.6 11228.8 11467.6 11667.0 11678.4 10384.9 10484.3 10690.5 10763.5 11148.7 11570.2 11927.1 11940.9 11949.6 11967.7 7336.0 8538.0 8558.7 8573.1 8574.2 11129.9 11408.8 11563.6 11642.8 11735.3 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6667.59 Median:2475.95 stddev:4194.69 (169.4%) stddev-sans-first:4186.85 08:50:50 INFO - PID 1640 | Values: 1270.9 1426.3 1435.9 1443.1 1459.4 10392.4 10725.8 10839.9 10845.0 11012.4 10761.1 10929.3 10945.9 10955.5 10957.7 2439.9 2460.1 2468.5 2475.7 2476.2 2336.7 2393.5 2408.4 2414.5 2420.2 2410.0 2435.4 2458.9 2461.3 2465.7 2358.6 2434.7 2446.4 2446.8 2452.2 2056.0 2147.6 2335.3 2401.6 2454.8 10865.0 10908.7 10909.7 10915.7 10918.0 6149.2 6424.5 6442.0 6449.8 6456.2 3875.8 3924.5 4020.1 4041.1 4069.5 954.7 1162.3 1165.4 1223.2 1224.7 15308.0 15323.1 15430.4 15469.1 15490.7 5284.4 5333.6 5414.4 5468.3 5520.0 9138.6 11178.1 11354.3 11402.2 11429.8 5921.5 6247.5 6256.5 6261.2 6274.2 4337.5 5140.2 5759.9 5762.2 5822.5 3705.2 4130.7 4135.2 4226.6 4319.6 1268.6 1273.1 1274.4 1289.5 1290.1 645.4 731.9 744.2 759.1 776.1 4857.9 5752.6 5964.0 6018.1 6147.5 554.7 559.9 576.5 577.7 589.6 2984.3 3555.8 3684.4 3705.2 3723.5 13611.7 13779.3 14119.4 14138.9 14190.8 13525.4 13647.0 13785.1 13822.7 13931.9 8897.0 10054.8 10098.7 10199.2 10252.9 6298.7 6309.1 6321.7 6328.5 6351.8 11947.4 12322.1 12335.3 12351.7 12388.7 12193.7 12433.4 12465.3 12515.2 12772.3 7757.0 7822.7 8842.2 9020.6 9036.5 10071.3 10106.3 10113.8 10169.1 10175.6 7885.4 7919.8 8383.3 8652.4 8682.2 8343.9 8347.9 8407.8 8465.2 8490.8 6586.7 7145.7 7619.0 7804.9 7809.5 5732.1 5839.0 5917.3 5972.1 5993.8 5907.4 5991.7 6002.0 6006.3 6045.7 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5352.87 Median:5830.71 stddev:1659.91 (28.5%) stddev-sans-first:1622.35 08:50:50 INFO - PID 1640 | Values: 397.0 400.0 400.8 401.4 403.5 5718.9 6358.7 6410.9 6479.8 6488.3 5467.8 5846.9 6222.6 6264.9 6290.7 4897.5 4902.9 4903.4 4916.7 4927.5 4801.3 4935.9 4947.5 4962.4 4964.3 4772.9 4819.3 4859.2 4860.4 4860.6 4465.0 4806.7 4857.9 4865.0 4892.3 3503.6 4306.0 4319.2 4320.9 4332.8 5814.5 6059.8 6131.0 6141.6 6150.5 4319.9 5419.4 5448.6 5469.2 5523.3 5948.6 6126.7 6679.7 6724.3 6735.5 3700.5 3894.2 4445.7 4446.3 4448.3 6753.8 6998.7 7022.5 7134.0 7135.5 4138.7 4147.5 4515.3 4528.1 4561.3 3823.6 4624.9 4641.3 4647.9 4649.5 5453.3 5453.8 5454.7 5482.6 5494.0 5977.9 6055.1 6487.6 6740.3 6745.4 1549.5 1569.0 1572.1 1575.1 1584.4 903.2 964.8 969.2 1026.2 1031.5 5020.8 5308.6 6311.2 6318.2 6343.6 5755.0 5954.8 6159.4 6584.7 6920.3 4798.4 5544.4 6022.9 6217.3 6259.8 3470.3 3612.2 3619.2 3651.6 3657.9 5629.0 6970.8 7033.4 7122.2 7161.9 5651.0 6335.9 6792.5 7128.1 7202.7 5486.3 5526.8 5642.6 6782.1 7070.4 6546.4 6895.7 6965.8 7022.6 7047.6 5670.5 5757.8 6514.9 6845.2 6856.5 6544.9 6620.9 7197.4 7243.9 7257.7 3719.3 3859.7 4230.5 4265.1 4552.3 5241.5 5377.5 6309.7 6987.4 7060.0 5928.9 6726.0 6747.6 6755.0 6786.5 5465.0 5718.0 6228.3 6739.7 6753.2 6515.7 6577.6 6619.9 6620.5 6634.7 6475.6 6666.6 6761.3 6762.3 6772.8 6585.4 6587.3 6603.4 6609.4 6625.5 08:50:50 INFO - PID 1640 | 08:50:50 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:18980.38 Median:39791.20 stddev:26705.53 (67.1%) stddev-sans-first:26745.15 08:50:50 INFO - PID 1640 | Values: 694.7 720.2 724.1 724.8 730.9 12425.1 15712.0 16021.9 16027.5 16144.9 14832.3 14919.5 14941.5 15024.2 15050.7 877.3 916.7 923.7 924.2 965.0 945.2 948.8 964.1 964.7 967.4 999.3 1007.5 1013.8 1014.3 1016.9 1003.5 1008.5 1009.7 1009.8 1012.2 740.7 747.4 749.2 749.9 751.7 14878.3 16183.5 16234.8 16597.9 16641.3 14468.9 14576.9 14578.1 14678.6 14701.8 11636.8 11755.2 11950.6 12028.2 12062.8 2990.1 3019.5 3047.6 3104.9 3106.0 97405.0 97926.0 98116.0 102542.5 103976.0 9055.0 9545.8 10191.0 10516.7 11692.5 21984.0 23983.4 24166.4 24188.3 24326.6 7841.1 7878.1 7988.4 8005.2 8071.5 4065.9 4168.8 4175.1 4182.9 4204.9 3494.5 3521.7 4211.7 4237.9 4240.4 751.1 842.7 848.8 850.7 857.6 536.3 542.7 549.8 561.9 570.6 9968.0 10198.4 10273.7 10277.5 10326.5 525.5 550.6 566.6 577.0 594.6 4543.7 4745.2 5120.6 5198.9 5223.1 80766.2 94030.1 95601.5 96736.0 97668.5 80991.0 82420.6 83747.3 86257.6 88339.0 73727.6 73733.6 74408.6 75551.0 80617.2 10542.2 10794.6 10968.8 11044.5 11184.5 31556.4 38149.4 39685.8 39687.4 39895.0 48508.5 48703.8 48831.5 48875.0 49140.8 13169.4 13220.7 13224.0 13272.3 13660.2 11387.7 12095.0 14014.4 14398.3 14735.9 10700.6 11464.7 11890.9 13631.0 13837.0 13296.6 13435.9 13540.7 13612.5 14044.0 12908.9 12939.6 13084.3 13495.6 13663.9 8999.0 9228.6 9264.3 9388.0 9415.6 7686.5 8718.9 9646.0 10679.8 10880.7 08:50:50 INFO - PID 1640 | -------- Summary: end -------- 08:50:50 INFO - PID 1640 | 08:50:51 INFO - TEST-INFO | 1640: exit 0 08:50:51 INFO - TEST-OK | dromaeo_css | took 1117672ms 08:50:51 INFO - TEST-START | kraken 08:50:51 INFO - operating with platform_type : mac_ 08:50:51 INFO - Initialising browser for kraken test... 08:50:51 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpr8EOpg/profile http://localhost:49248/getInfo.html) 08:50:54 INFO - PID 1652 | 2017-03-23 08:50:54.291 plugin-container[1653:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 08:50:54 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:54 INFO - PID 1652 | 2017-03-23 08:50:54.296 plugin-container[1653:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 08:50:54 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:54 INFO - PID 1652 | 2017-03-23 08:50:54.296 plugin-container[1653:10405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:54 INFO - PID 1652 | __metrics Screen width/height:1600/1200 08:50:54 INFO - PID 1652 | colorDepth:24 08:50:54 INFO - PID 1652 | Browser inner width/height: 1024/689 08:50:54 INFO - PID 1652 | __metrics 08:50:54 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3388: TypeError: this.tabs is undefined 08:50:55 INFO - TEST-INFO | 1652: exit 0 08:50:55 INFO - Browser initialized. 08:50:55 INFO - Running cycle 1/1 for kraken test... 08:50:55 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpr8EOpg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:57 INFO - PID 1654 | 2017-03-23 08:50:57.865 plugin-container[1655:10537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 08:50:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:57 INFO - PID 1654 | 2017-03-23 08:50:57.865 plugin-container[1655:10537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 08:50:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:57 INFO - PID 1654 | 2017-03-23 08:50:57.865 plugin-container[1655:10537] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:07 INFO - PID 1654 | RSS: Main: 227762176 08:51:07 INFO - PID 1654 | 08:51:10 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:11 INFO - PID 1654 | RSS: Main: 242053120 08:51:11 INFO - PID 1654 | 08:51:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 08:51:14 INFO - PID 1654 | RSS: Main: 250576896 08:51:14 INFO - PID 1654 | 08:51:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 08:51:18 INFO - PID 1654 | RSS: Main: 253423616 08:51:18 INFO - PID 1654 | 08:51:20 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 08:51:21 INFO - PID 1654 | RSS: Main: 254586880 08:51:21 INFO - PID 1654 | 08:51:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:24 INFO - PID 1654 | RSS: Main: 254992384 08:51:24 INFO - PID 1654 | 08:51:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:28 INFO - PID 1654 | RSS: Main: 261636096 08:51:28 INFO - PID 1654 | 08:51:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:31 INFO - PID 1654 | RSS: Main: 262238208 08:51:31 INFO - PID 1654 | 08:51:34 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 08:51:35 INFO - PID 1654 | RSS: Main: 262426624 08:51:35 INFO - PID 1654 | 08:51:37 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:37 INFO - PID 1654 | RSS: Main: 261853184 08:51:37 INFO - PID 1654 | 08:51:39 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:40 INFO - PID 1654 | RSS: Main: 261836800 08:51:40 INFO - PID 1654 | 08:51:42 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:43 INFO - PID 1654 | RSS: Main: 262225920 08:51:43 INFO - PID 1654 | 08:51:46 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:46 INFO - PID 1654 | RSS: Main: 262356992 08:51:46 INFO - PID 1654 | 08:51:50 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:50 INFO - PID 1654 | RSS: Main: 262090752 08:51:50 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 08:51:52 INFO - PID 1654 | RSS: Main: 263712768 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | __start_tp_report 08:51:52 INFO - PID 1654 | _x_x_mozilla_page_load 08:51:52 INFO - PID 1654 | _x_x_mozilla_page_load_details 08:51:52 INFO - PID 1654 | |i|pagename|runs| 08:51:52 INFO - PID 1654 | |0;ai-astar;107;172;161;116;115;116;116;115;115;116 08:51:52 INFO - PID 1654 | |1;audio-beat-detection;136;117;117;153;114;118;114;114;138;151 08:51:52 INFO - PID 1654 | |2;audio-dft;196;206;204;214;223;195;223;280;201;193 08:51:52 INFO - PID 1654 | |3;audio-fft;89;87;84;92;61;74;61;61;71;62 08:51:52 INFO - PID 1654 | |4;audio-oscillator;92;69;87;102;105;103;91;70;71;85 08:51:52 INFO - PID 1654 | |5;imaging-gaussian-blur;134;136;92;138;92;88;135;80;134;91 08:51:52 INFO - PID 1654 | |6;imaging-darkroom;96;96;153;98;98;99;99;98;98;99 08:51:52 INFO - PID 1654 | |7;imaging-desaturate;91;95;91;126;102;80;142;142;76;142 08:51:52 INFO - PID 1654 | |8;json-parse-financial;66;66;67;67;68;66;66;67;67;68 08:51:52 INFO - PID 1654 | |9;json-stringify-tinderbox;59;61;59;60;63;59;60;58;59;60 08:51:52 INFO - PID 1654 | |10;stanford-crypto-aes;83;81;83;85;85;85;83;86;89;83 08:51:52 INFO - PID 1654 | |11;stanford-crypto-ccm;140;144;145;138;137;145;143;144;214;169 08:51:52 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;217;233;190;191;193;220;192;193;191;191 08:51:52 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;71;90;95;103;76;69;68;70;67;70 08:51:52 INFO - PID 1654 | __end_tp_report 08:51:52 INFO - PID 1654 | __start_cc_report 08:51:52 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1005 08:51:52 INFO - PID 1654 | __end_cc_report 08:51:52 INFO - PID 1654 | __startTimestamp1490284312800__endTimestamp 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | ------- Summary: start ------- 08:51:52 INFO - PID 1654 | Number of tests: 14 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:124.90 Median:116.00 stddev:22.24 (19.2%) stddev-sans-first:22.63 08:51:52 INFO - PID 1654 | Values: 107.0 172.0 161.0 116.0 115.0 116.0 116.0 115.0 115.0 116.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:127.00 stddev:15.78 (12.4%) stddev-sans-first:16.41 08:51:52 INFO - PID 1654 | Values: 136.0 117.0 117.0 153.0 114.0 118.0 114.0 114.0 138.0 151.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:213.50 Median:210.00 stddev:25.76 (12.3%) stddev-sans-first:26.54 08:51:52 INFO - PID 1654 | Values: 196.0 206.0 204.0 214.0 223.0 195.0 223.0 280.0 201.0 193.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:74.20 Median:79.00 stddev:12.81 (16.2%) stddev-sans-first:12.42 08:51:52 INFO - PID 1654 | Values: 89.0 87.0 84.0 92.0 61.0 74.0 61.0 61.0 71.0 62.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:78.00 stddev:13.81 (17.7%) stddev-sans-first:14.55 08:51:52 INFO - PID 1654 | Values: 92.0 69.0 87.0 102.0 105.0 103.0 91.0 70.0 71.0 85.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:112.00 Median:84.00 stddev:24.92 (29.7%) stddev-sans-first:25.13 08:51:52 INFO - PID 1654 | Values: 134.0 136.0 92.0 138.0 92.0 88.0 135.0 80.0 134.0 91.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:98.00 stddev:17.46 (17.8%) stddev-sans-first:18.32 08:51:52 INFO - PID 1654 | Values: 96.0 96.0 153.0 98.0 98.0 99.0 99.0 98.0 98.0 99.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:108.70 Median:78.00 stddev:26.62 (34.1%) stddev-sans-first:27.45 08:51:52 INFO - PID 1654 | Values: 91.0 95.0 91.0 126.0 102.0 80.0 142.0 142.0 76.0 142.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 08:51:52 INFO - PID 1654 | Values: 66.0 66.0 67.0 67.0 68.0 66.0 66.0 67.0 67.0 68.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:1.40 (2.3%) stddev-sans-first:1.45 08:51:52 INFO - PID 1654 | Values: 59.0 61.0 59.0 60.0 63.0 59.0 60.0 58.0 59.0 60.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:2.21 (2.6%) stddev-sans-first:2.30 08:51:52 INFO - PID 1654 | Values: 83.0 81.0 83.0 85.0 85.0 85.0 83.0 86.0 89.0 83.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:144.50 stddev:23.58 (16.3%) stddev-sans-first:24.62 08:51:52 INFO - PID 1654 | Values: 140.0 144.0 145.0 138.0 137.0 145.0 143.0 144.0 214.0 169.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.10 Median:193.00 stddev:15.88 (8.2%) stddev-sans-first:15.77 08:51:52 INFO - PID 1654 | Values: 217.0 233.0 190.0 191.0 193.0 220.0 192.0 193.0 191.0 191.0 08:51:52 INFO - PID 1654 | 08:51:52 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.90 Median:70.50 stddev:13.08 (18.6%) stddev-sans-first:13.64 08:51:52 INFO - PID 1654 | Values: 71.0 90.0 95.0 103.0 76.0 69.0 68.0 70.0 67.0 70.0 08:51:52 INFO - PID 1654 | -------- Summary: end -------- 08:51:52 INFO - PID 1654 | 08:51:53 INFO - TEST-INFO | 1654: exit 0 08:51:53 INFO - TEST-OK | kraken | took 62425ms 08:51:53 INFO - SUITE-END | took 1180s 08:51:53 INFO - Completed test suite (00:19:40) 08:51:53 INFO - javascript benchmark 08:51:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7608.684543125515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2771.847548384839, 3544.4328907374825, 3544.6100928897818, 3546.680798728114, 3607.9458808117884, 6118.253719382601, 6193.566450348471, 6220.564560480762, 6235.875282494353, 6240.3759624037575, 6110.649890384128, 6169.660668663222, 6216.658083805389, 6255.687215639211, 6318.368163183691, 5668.461496157858, 5705.830304787509, 5777.046787280094, 5822.301323841124, 5849.853753656172, 4997.652802553757, 5606.21512988182, 5779.884402311951, 5787.739551720183, 5820.330661973863, 5500.339959204881, 5589.217509548666, 5738.225339579187, 5743.798967036134, 5765.308163020443, 5587.8882422351735, 5640.74616642248, 5662.150677398382, 5682.715864206773, 5730.598858079936, 5510.090835012218, 5594.608377413582, 5603.355614104348, 5637.210790489335, 5644.689542075184, 6064.332923378425, 6090.66501342426, 6258.718357673915, 6298.779542715983, 6314.084457753646, 6484.837879053014, 6579.342065793382, 6621.73513059477, 6634.270230274666, 6671.499637527205, 6741.629210393427, 6765.898511522331, 6782.694778734969, 6786.898196527056, 6788.966055169742, 6296.87406251877, 6298.559100862987, 6317.273513545909, 6338.461230795388, 6341.778037768702, 6630.900536491957, 6718.428933540704, 6801.489783842115, 6878.8624227515165, 6942.097527321416, 5556.472135147154, 5859.740155866509, 6325.240971083499, 6380.521460890452, 6403.711832967528, 6522.771702990372, 6642.402183803384, 6656.467482601381, 6746.359095885883, 6760.425363844129, 6662.367075127952, 6666.333366663295, 6674.16572928384, 6679.365460281266, 6685.298043705341, 5577.609567330329, 6435.871282574416, 6478.449331806755, 6489.1888513935755, 6689.0, 4668.346431499593, 5277.656952298088, 5295.7087360195555, 5346.572274218053, 5354.089804733205, 4719.905601888012, 4753.096911586787, 4761.40482439695, 4808.8797780055775, 4809.2064809306075, 6272.090546870678, 6289.7484100635465, 6329.303776584664, 6370.299267080617, 6374.968125159345, 4996.575291100297, 6531.575447595891, 6629.834254143685, 6748.460123190232, 6835.624040677811, 6345.841353966188, 6358.936410635927, 6382.712777924911, 6384.3934826191435, 6390.073439351361, 5985.670788106696, 6037.879242415127, 6038.969805150946, 6044.818655440344, 6078.787242446493, 6522.380373864476, 6628.27089020217, 6794.422474089758, 6856.657167141623, 7014.088168538057, 6741.0, 6819.965900170468, 6824.556403833735, 6840.623765692934, 6843.794686159423, 5743.56923230751, 6890.0, 6898.551594146365, 6901.62041087745, 6933.306669333027, 5898.64331203817, 6259.280182778856, 6374.07575901501, 6431.0, 6612.8346791330605, 6724.327567243236, 6725.39471447572, 6788.898166527407, 6805.455563554805, 6821.658917054227, 6538.0, 6540.574862633914, 6543.640099794557, 6550.7707230246715, 6551.475881929529, 6267.435930766253, 6279.466245369194, 6488.480921526361, 6547.279799222177, 6568.9014664781025, 6183.938160618336, 6252.812415627538, 6358.841028974128, 6413.647249401328, 6428.164338635947, 6528.640924749185, 6546.934530654823, 6564.081028655896, 6568.7372505101175, 6609.843277426335, 6227.813165605039, 6252.968735156295, 6301.716422761078, 6316.115743795965, 6386.105945167587], "value": 6108.399130908017, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.2000799120967, 801.058755961745, 821.8109834738008, 1252.7932891072974, 1262.1291308996797, 37515.43726844096, 38658.87376194315, 38677.61322386775, 38686.61313386869, 38843.611563884355, 38320.61679383205, 38615.806920965355, 38687.83936481903, 38763.612363876426, 38860.0, 8067.596620168997, 8251.95874020632, 8518.87221691675, 8727.95636021819, 8734.86897696535, 8557.272631826303, 8643.827123457528, 8663.870041949376, 8671.523066231355, 8688.174623410765, 8632.741017769446, 8648.481091134521, 8716.694915677921, 8718.956405217965, 8736.388452808305, 8667.046624871258, 8692.261157801595, 8698.999615044288, 8774.674711201536, 8811.0, 8656.307495400353, 8714.869276960788, 8742.737717868475, 8766.780830479225, 8768.430052046597, 38035.429468557995, 38341.0, 38369.42445863314, 38410.03974900622, 38642.61357386418, 3047.177262139232, 3078.291992841637, 3079.4610943085095, 3085.598872146629, 3093.520504321834, 27427.588586171023, 27812.860935695193, 28040.0, 28154.859225703945, 28838.423231535253, 2725.1960671601923, 2736.3569561153113, 2737.117102744104, 2750.614913912054, 2831.164806382122, 210616.0, 213399.0, 233831.0, 234350.0, 263221.0516842268, 37786.43320350197, 46193.61419157364, 46442.303365449545, 46535.30197047047, 46597.53402465932, 37309.44035839465, 45147.0, 48935.5106448931, 48977.75511122493, 49005.01989960181, 13361.997850161277, 13474.86525134736, 13484.595462136152, 13518.459261629621, 13529.052966292462, 11042.889571104346, 11138.49876755548, 11188.888111118948, 11194.832077518844, 11205.943970280096, 11147.0, 13878.097923634932, 13907.443702252, 13927.442902283998, 14006.019578629603, 5595.272614560081, 5790.826275211751, 6079.878402431927, 6086.147939288503, 6124.050772130327, 869.3393021303855, 905.7599736069947, 950.1543626172712, 965.7392504024015, 1001.1690297053574, 23317.41706457352, 24370.268891932916, 24698.13556525513, 24836.627450588254, 24870.626940595906, 1058.8200005999, 1058.9046985771317, 1091.5961094395125, 1094.819354806448, 1117.1844553475846, 10296.794064118676, 11769.941150294193, 12291.631251062483, 12302.876971230353, 12453.0, 421531.0, 421573.0, 423127.0, 423713.0, 424114.0, 54587.727061364436, 54892.45107548873, 54900.0, 55009.1748623771, 55011.0, 1465.3113036872653, 1492.1121932450392, 1506.6158129676862, 1510.2750679673818, 1510.4939845151778, 23038.769612303662, 23966.041358345472, 25942.481150377265, 28080.578791318556, 28904.710952891044, 53828.0, 54179.0, 54283.45716542784, 54321.45678543164, 54433.0, 427952.0, 428211.0, 429995.0, 433064.8346758246, 433225.0, 26829.865850670623, 27402.17793466199, 32869.17827054247, 34806.0, 35813.1046723834, 33122.66877331292, 34942.77700280478, 39746.0, 40716.79641601773, 41269.793651032756, 28088.719112809427, 29685.0, 29963.400731984802, 30057.248568785082, 30398.240043999078, 28941.85529072341, 30006.0, 30175.403577933404, 30225.848870755504, 30299.242518937204, 35066.47400288947, 35391.0, 36213.27573448569, 36218.81890590636, 36348.818255908554, 22337.0, 28022.299442513286, 29454.558181627723, 29693.40613187681, 29868.253293666963, 22551.32346029622, 23550.76449235554, 23831.64252536248, 24043.759562404153, 24055.759442405353], "value": 17501.13447777207, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.19852585247565, 416.448206126882, 421.6479239834738, 429.6734481793837, 439.5889842996796, 19360.419187424366, 20601.484962875937, 21302.57394852102, 21359.932003399826, 21508.247211347607, 19975.900120499413, 21833.67249491255, 21891.781082189213, 22095.0, 23074.307770766864, 6142.877142457137, 6344.968275158618, 6351.269603995554, 6365.52258580606, 6368.203974503187, 5045.0, 6214.0, 6280.905786413207, 6285.842853928642, 6341.397567231105, 5636.689982051006, 6020.825938941908, 6259.71831267594, 6330.113784070234, 6333.145025421555, 6103.87792244157, 6196.907046394308, 6307.053941908727, 6366.267879193906, 6373.203349581326, 5586.553075753971, 5597.61413168158, 5668.801591944262, 5697.1169468732, 5714.371419143891, 26211.73788262112, 27419.725802741916, 27691.584626230622, 27745.445091098267, 27861.860690696416, 2025.422754514956, 2029.6245194639039, 2037.836973042153, 2066.545360020808, 2081.5004398944297, 12170.513179472811, 15935.123568203744, 16046.598835029217, 16317.265723042372, 17206.913965430216, 1675.6367965475358, 1707.9573010674708, 1708.7864016997876, 1716.1933891071176, 1717.2444124585268, 139199.0, 145883.2705836464, 146036.53963460337, 146928.0, 148963.0, 12495.062870284764, 12495.625131246077, 12506.437210325557, 12656.36718164088, 12813.679658008437, 16201.75697364517, 16216.75674864878, 16220.918895405683, 16248.025118492928, 16249.512514624581, 6768.390844823989, 7047.295270472911, 8434.536100514531, 8778.990416102102, 8813.867791983126, 8063.758087257392, 8068.9596552016865, 8083.312918402002, 8187.181281871765, 8214.917850821415, 7811.4922530035365, 7970.290101365564, 8019.0, 8097.716579919792, 8101.878471822927, 2162.88104154273, 2179.869207847534, 2180.5856887191385, 2181.2692747929623, 2361.6457531370434, 904.7331037344, 907.7276816954887, 908.1599520443564, 946.588234118147, 961.5240455974198, 19533.511662208275, 20068.197272109242, 20084.89957550203, 20443.08006139727, 20595.176192952113, 946.4794363100267, 964.1033838530234, 965.7295957132014, 966.6133546581283, 990.0742805476903, 9003.864942025875, 10074.0, 10119.494025298705, 10377.11794497461, 10486.685399438029, 594247.0287648534, 602711.0, 604571.0, 627993.0, 651463.0, 116503.0, 122917.0, 138292.0, 149013.0, 153134.23432882765, 1113.1317572293785, 1194.0447641886456, 1203.1157099531838, 1229.7601967616229, 1229.9200551964093, 17680.027598482207, 17706.734398984285, 17776.555586109935, 17824.55438613993, 18145.45563633093, 40631.593684062784, 60944.39055609565, 61268.0, 61433.07850382158, 62664.48026236881, 84371.0, 84971.30057398941, 85526.0, 85917.28165436782, 87176.56411718155, 6692.330766923464, 8318.70884519039, 8433.578321083798, 8500.91499085026, 8757.080506546721, 12176.634700958986, 12919.935400322938, 13017.479300827861, 13031.47874085064, 13436.865631343562, 9914.553845076845, 11228.831567526331, 11467.598634047768, 11667.00830429423, 11678.357690327113, 10384.896151038392, 10484.318519296221, 10690.465476726287, 10763.51564179598, 11148.721281968015, 11570.247933884271, 11927.105467090176, 11940.940295298467, 11949.581764638197, 11967.700807479883, 7335.963320183365, 8538.0, 8558.67340562189, 8573.099824518336, 8574.185452382264, 11129.944350278196, 11408.828867566826, 11563.595274165364, 11642.76714465689, 11735.295882247092], "value": 10168.696848944006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.911036227464, 1426.343881814366, 1435.8635929586699, 1443.0908527627594, 1459.350588987901, 10392.428416437093, 10725.839112413301, 10839.945800270998, 10844.96972787586, 11012.39431831251, 10761.085307748832, 10929.344239345644, 10945.945270273658, 10955.452227388638, 10957.671269861898, 2439.890204940782, 2460.0897667862914, 2468.4939587384633, 2475.653408522808, 2476.2447453526665, 2336.745395193573, 2393.533261014103, 2408.397900524869, 2414.4929564791414, 2420.177139772477, 2410.0480310277517, 2435.3789783605243, 2458.8770561471943, 2461.310832966772, 2465.7410971847967, 2358.5872472317446, 2434.7443518430578, 2446.3639453742157, 2446.779789818916, 2452.2030340139527, 2055.969160462594, 2147.6134295826746, 2335.3344296875307, 2401.5557121932497, 2454.7545245475308, 10865.022148006718, 10908.672739817817, 10909.672709818638, 10915.727106822314, 10918.0, 6149.200603921507, 6424.486041116699, 6441.967790161019, 6449.774257900998, 6456.225252969627, 3875.8255878485534, 3924.50943632046, 4020.1356708307853, 4041.0503531670033, 4069.491313585802, 954.742219600704, 1162.2798258577352, 1165.3648761424959, 1223.1988047828686, 1224.7121926347302, 15308.004979676285, 15323.080615163126, 15430.38278468871, 15469.071855688695, 15490.690186196212, 5284.3658760948165, 5333.57331413486, 5414.431484694129, 5468.28912241406, 5519.972400137974, 9138.63445462174, 11178.105751539857, 11354.26197297173, 11402.20184587071, 11429.828552571718, 5921.467067963904, 6247.500199983991, 6256.499480041586, 6261.217347831521, 6274.247090349188, 4337.522872484024, 5140.203268493315, 5759.8560035999435, 5762.193292938992, 5822.505087067647, 3705.2219034002624, 4130.669546436338, 4135.234981528427, 4226.556211597799, 4319.632831209319, 1268.6384380451527, 1273.0579371265196, 1274.4201388368456, 1289.5164313382481, 1290.1033781521753, 645.4320198225626, 731.8536292741367, 744.1553836395622, 759.0928840036214, 776.1191048160379, 4857.9028419431415, 5752.626079304831, 5963.9563076461445, 6018.127371531191, 6147.508199344042, 554.6782865937746, 559.9300087489064, 576.5442258297832, 577.6793879396891, 589.5990726306153, 2984.328526081614, 3555.7510974231554, 3684.428913518409, 3705.2219034002624, 3723.497327860758, 13611.659708507168, 13779.311034448438, 14119.364628591738, 14138.929305353407, 14190.787138192936, 13525.39135738894, 13647.0, 13785.103968242032, 13822.654433639038, 13931.930340348234, 8896.955515222511, 10054.849177262347, 10098.747531311776, 10199.18406527476, 10252.948735256276, 6298.7480500781285, 6309.097815005149, 6321.715522801393, 6328.493720502441, 6351.841203969938, 11947.402629868646, 12322.075844311534, 12335.321557314433, 12351.69120771988, 12388.690282743004, 12193.69515762113, 12433.440495177558, 12465.314407707663, 12515.249085054926, 12772.297523636289, 7756.961215193888, 7822.748360262331, 8842.204201621886, 9020.639174432948, 9036.502992335485, 10071.295009349275, 10106.292559521056, 10113.797724045331, 10169.135623472088, 10175.592976280866, 7885.369170466463, 7919.762407127795, 8383.287420569077, 8652.394332396923, 8682.175193356621, 8343.874841877498, 8347.874781878398, 8407.831843362976, 8465.238128568211, 8490.787730306545, 6586.736530538725, 7145.749898753519, 7619.047619047619, 7804.882926756217, 7809.531428114331, 5732.140178973187, 5839.036558967892, 5917.319508256605, 5972.074328479092, 5993.820185394445, 5907.375471745305, 5991.700414979321, 6002.039673652194, 6006.279246490449, 6045.727942242522], "value": 5015.866487604619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.99758110770284, 399.9880003599892, 400.7815740421468, 401.3938952182204, 403.541979852867, 5718.942810571898, 6358.713857876395, 6410.935890641093, 6479.805605831821, 6488.253850807158, 5467.753951072202, 5846.88306233875, 6222.5644204905675, 6264.906026409596, 6290.665947048061, 4897.461279259279, 4902.926456103161, 4903.362562866841, 4916.729579873106, 4927.50724927508, 4801.255805350159, 4935.925961110586, 4947.529984651453, 4962.379702537183, 4964.255361695757, 4772.8999240334315, 4819.301201325806, 4859.173940430118, 4860.441049279325, 4860.5868501177265, 4464.999999999984, 4806.711597304173, 4857.902841943177, 4865.0, 4892.339534162914, 3503.5985605757646, 4305.9784701076605, 4319.179355922396, 4320.935185972213, 4332.848350307756, 5814.534837213013, 6059.757609695607, 6130.96934515329, 6141.631502109888, 6150.50795936324, 4319.913601727948, 5419.430959749209, 5448.618596698193, 5469.234307196995, 5523.337199536081, 5948.553858460633, 6126.713390188066, 6679.7249760153545, 6724.293949135319, 6735.528513004092, 3700.481932529448, 3894.240623078501, 4445.711028783151, 4446.333050042487, 4448.266036104039, 6753.763618273337, 6998.650067496605, 7022.52526969337, 7134.0, 7135.4648401370105, 4138.730982486129, 4147.502299724052, 4515.2549829277805, 4528.0943811237885, 4561.338605902126, 3823.5985221551337, 4624.930626040612, 4641.303804429363, 4647.86056418308, 4649.488556258809, 5453.291072160594, 5453.809116680897, 5454.699991500505, 5482.5888058394985, 5494.038543254915, 5977.850553736104, 6055.122007308915, 6487.578307410097, 6740.258571557125, 6745.392914637706, 1549.457689808558, 1568.9921550392176, 1572.1039007765692, 1575.102191750714, 1584.3857634902504, 903.2367649336301, 964.7940074906367, 969.2391472693902, 1026.2457094035879, 1031.5203430404897, 5020.849374518771, 5308.629020571964, 6311.179546658905, 6318.210223722035, 6343.619382837044, 5754.971225143848, 5954.761809527657, 6159.384061593805, 6584.703688334036, 6920.30796920304, 4798.4001999750035, 5544.417836127229, 6022.855657425075, 6217.316384886175, 6259.78090766821, 3470.288590838872, 3612.205314830733, 3619.2399596084592, 3651.6348365163803, 3657.8719744808805, 5629.0, 6970.826499676934, 7033.366996970297, 7122.18094919091, 7161.856762864713, 5651.0, 6335.904961425582, 6792.539603985068, 7128.073350464407, 7202.71189152428, 5486.31421072366, 5526.834194974157, 5642.576806739593, 6782.101011716727, 7070.399016083587, 6546.443552298108, 6895.6552172390175, 6965.756198533027, 7022.613756243456, 7047.577145371294, 5670.4689733772475, 5757.827265182052, 6514.869702605826, 6845.247022827385, 6856.5200435969, 6544.854650436059, 6620.933790662032, 7197.424206063398, 7243.855122897618, 7257.745978890714, 3719.3119272934596, 3859.749116307431, 4230.534641189528, 4265.104328091012, 4552.317152427162, 5241.528262456397, 5377.489138531833, 6309.747610095545, 6987.406070484066, 7060.0, 5928.9407105928385, 6726.02472641474, 6747.595144291358, 6755.0, 6786.4910131739325, 5465.0, 5718.027935250934, 6228.346023667631, 6739.663016849236, 6753.223379311442, 6515.706793194223, 6577.566300468857, 6619.867602647823, 6620.470362370903, 6634.701438435379, 6475.579087359307, 6666.633335166613, 6761.323867613396, 6762.323767623198, 6772.7629532968085, 6585.440237579859, 6587.308332625005, 6603.372679595431, 6609.372109649768, 6625.503087268377], "value": 4833.842843671208, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.7221111555381, 720.2221600671272, 724.1021133794094, 724.7753196509083, 730.8757511223091, 12425.068119890999, 15712.0, 16021.919890400532, 16027.519174424757, 16144.919275403621, 14832.332545035473, 14919.477818276362, 14941.477048303312, 15024.248787560633, 15050.698986020274, 877.2587163846539, 916.6608354908693, 923.6767131503987, 924.2328867040374, 964.9951750241239, 945.1776954049986, 948.7533241357228, 964.1178321835512, 964.6720115160844, 967.3712087143343, 999.3004896572392, 1007.4811472091878, 1013.7516308504427, 1014.345746993189, 1016.8525563793246, 1003.4531180506641, 1008.5259927833906, 1009.6718566465928, 1009.808136454075, 1012.2408193854609, 740.7222291640635, 747.4431548496351, 749.1534565940507, 749.8837680159584, 751.70683433461, 14878.256087195598, 16183.514494565183, 16234.837651623451, 16597.91701041487, 16641.33434662612, 14468.927655361762, 14576.93797872018, 14578.052426592238, 14678.633034174125, 14701.779473307908, 11636.825447618292, 11755.235909665844, 11950.581729639507, 12028.218165819193, 12062.819057714141, 2990.132861470171, 3019.4564978303897, 3047.5733397324393, 3104.8758049677986, 3105.968940310613, 97405.0, 97926.0, 98116.0, 102542.48728756382, 103975.96024039738, 9054.954725226331, 9545.809083818423, 10191.0, 10516.68449946503, 11692.47383867728, 21984.0, 23983.441076329982, 24166.395840103785, 24188.274351769476, 24326.635100473508, 7841.137474877759, 7878.054633443909, 7988.360931125496, 8005.159458256916, 8071.542995164312, 4065.8780236592947, 4168.8332466701595, 4175.060611362419, 4182.862475861411, 4204.915901681949, 3494.4583589543663, 3521.6830485255937, 4211.7051806373875, 4237.87286381409, 4240.448741663564, 751.1075611825222, 842.7485184501011, 848.8047749017637, 850.6639877248452, 857.6183598298821, 536.3108405698658, 542.7204989430446, 549.7526113249054, 561.8904313658879, 570.6034306157181, 9967.950160249298, 10198.439085850201, 10273.691789246335, 10277.486125693686, 10326.535305911251, 525.4850246758199, 550.5664960872872, 566.577899464893, 576.974036168365, 594.6016169166669, 4543.704659197141, 4745.169595320874, 5120.61595380348, 5198.870028249324, 5223.059849227099, 80766.19233807705, 94030.0596994035, 95601.5219923896, 96736.0, 97668.51165744125, 80991.0, 82420.58789706013, 83747.32505349981, 86257.5687121573, 88339.0, 73727.63136184392, 73733.631331843, 74408.62795685987, 75551.0, 80617.19382806332, 10542.209334300112, 10794.56821727122, 10968.780624387628, 11044.502997364978, 11184.496697648789, 31556.36887262288, 38149.4277585842, 39685.801570991964, 39687.40468892911, 39895.0, 48508.51491485181, 48703.756481217366, 48831.535053948435, 48875.0, 49140.75429622972, 13169.407376668265, 13220.735585288432, 13224.0, 13272.336383180995, 13660.248686322348, 11387.658370248906, 12095.032397408011, 14014.36935337892, 14398.280085995868, 14735.92632036833, 10700.571977120828, 11464.713382165513, 11890.881091188978, 13630.977676674487, 13836.962227832752, 13296.601101966957, 13435.932820336227, 13540.729185416434, 13612.455501780214, 14044.016918815585, 12908.935455322664, 12939.611811645289, 13084.28036458004, 13495.59513214605, 13663.925616736155, 8999.0, 9228.584713687766, 9264.258859291105, 9387.953060234928, 9415.62337506492, 7686.538807671557, 8718.869216961624, 9646.0, 10679.839802403125, 10880.67357979262], "value": 7360.85897870186, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 172.0, 161.0, 116.0, 115.0, 116.0, 116.0, 115.0, 115.0, 116.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 117.0, 117.0, 153.0, 114.0, 118.0, 114.0, 114.0, 138.0, 151.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 206.0, 204.0, 214.0, 223.0, 195.0, 223.0, 280.0, 201.0, 193.0], "value": 213.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 84.0, 92.0, 61.0, 74.0, 61.0, 61.0, 71.0, 62.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 87.0, 102.0, 105.0, 103.0, 91.0, 70.0, 71.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 92.0, 138.0, 92.0, 88.0, 135.0, 80.0, 134.0, 91.0], "value": 112.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 153.0, 98.0, 98.0, 99.0, 99.0, 98.0, 98.0, 99.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 126.0, 102.0, 80.0, 142.0, 142.0, 76.0, 142.0], "value": 108.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 68.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 60.0, 63.0, 59.0, 60.0, 58.0, 59.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 85.0, 85.0, 85.0, 83.0, 86.0, 89.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 144.0, 145.0, 138.0, 137.0, 145.0, 143.0, 144.0, 214.0, 169.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 233.0, 190.0, 191.0, 193.0, 220.0, 192.0, 193.0, 191.0, 191.0], "value": 201.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 90.0, 95.0, 103.0, 76.0, 69.0, 68.0, 70.0, 67.0, 70.0], "value": 77.9, "unit": "score"}]}]} 08:51:54 INFO - Return code: 0 08:51:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:54 ERROR - # TBPL SUCCESS # 08:51:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:54 INFO - 2017-03-23 08:51:54.164 screenresolution[1659:10923] starting screenresolution argv=screenresolution get 08:51:54 INFO - 2017-03-23 08:51:54.167 screenresolution[1659:10923] Display 0: 1600x1200x32@60 08:51:54 INFO - 2017-03-23 08:51:54.173 screenresolution[1660:10926] starting screenresolution argv=screenresolution list 08:51:54 INFO - Available Modes on Display 0 08:51:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:54 INFO - Intel Iris: 08:51:54 INFO - Chipset Model: Intel Iris 08:51:54 INFO - Type: GPU 08:51:54 INFO - Bus: Built-In 08:51:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:54 INFO - Vendor: Intel (0x8086) 08:51:54 INFO - Device ID: 0x0a2e 08:51:54 INFO - Revision ID: 0x0009 08:51:54 INFO - Displays: 08:51:54 INFO - Display: 08:51:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:54 INFO - Display Serial Number: 1600x1200 60 08:51:54 INFO - Main Display: Yes 08:51:54 INFO - Mirror: Off 08:51:54 INFO - Online: Yes 08:51:54 INFO - Rotation: Supported 08:51:54 INFO - Return code: 0 08:51:54 INFO - Running post-action listener: _resource_record_post_action 08:51:54 INFO - [mozharness: 2017-03-23 15:51:54.414020Z] Finished run-tests step (success) 08:51:54 INFO - Running post-run listener: _resource_record_post_run 08:51:54 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39315968; Write bytes: 516623360; Read time: 252; Write time: 1624 08:51:54 INFO - TinderboxPrint: CPU usage
54.0% 08:51:54 INFO - TinderboxPrint: I/O read bytes / time
39,315,968 / 252 08:51:54 INFO - TinderboxPrint: I/O write bytes / time
516,623,360 / 1,624 08:51:54 INFO - TinderboxPrint: CPU idle
2,210.9 (46.0%) 08:51:54 INFO - TinderboxPrint: CPU system
963.3 (20.0%) 08:51:54 INFO - TinderboxPrint: CPU user
1,631.5 (33.9%) 08:51:54 INFO - TinderboxPrint: Swap in / out
250,175,488 / 0 08:51:54 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199844352; Write bytes: 194545664; Read time: 15620; Write time: 414 08:51:54 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 38353408; Write bytes: 302265344; Read time: 239; Write time: 1149 08:51:54 INFO - Running post-run listener: _upload_blobber_files 08:51:54 INFO - Blob upload gear active. 08:51:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:54 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 08:51:54 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'] 08:51:54 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 08:51:55 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:56 INFO - (blobuploader) - INFO - Done attempting. 08:51:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:51:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:51:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:57 INFO - (blobuploader) - INFO - Done attempting. 08:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:51:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:58 INFO - (blobuploader) - INFO - Done attempting. 08:51:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:52:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:00 INFO - (blobuploader) - INFO - Done attempting. 08:52:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:52:00 INFO - Return code: 0 08:52:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:52:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:52:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9bd1a981821a49c71e3d0020b60a90c3f5547d4cb6f1722be32b29d7c8486d780d6ba0f170f1f2f8bad85cd2699827e5c3c28f158fd78b33c79eeb2220f3b77", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1092021e2d60aa797e4c72f8a437e3a51460cfb063f0a451519ab080816fa69690f0f9cc5b77e4544b6dd846c6bf270c8c8cc07a354b3399ca43587695ecae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee50418c419994ea9215b0de2eae0fbbc285918251904bdf5b3d3d3c508de6d0fea355183b69d60ff79c74c6b044ca8e98183a4cca5e4756913981be64ba7432", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/731d89a6af3c3b70b17864a864b95866848476ec01e3da6637b163c1f9bcbb6ac66c371ff8fb88cfb40ef374d1544782b72e654e0654da4c106aad0dfeabc80d"} 08:52:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:52:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:52:00 INFO - Contents: 08:52:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9bd1a981821a49c71e3d0020b60a90c3f5547d4cb6f1722be32b29d7c8486d780d6ba0f170f1f2f8bad85cd2699827e5c3c28f158fd78b33c79eeb2220f3b77", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1092021e2d60aa797e4c72f8a437e3a51460cfb063f0a451519ab080816fa69690f0f9cc5b77e4544b6dd846c6bf270c8c8cc07a354b3399ca43587695ecae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee50418c419994ea9215b0de2eae0fbbc285918251904bdf5b3d3d3c508de6d0fea355183b69d60ff79c74c6b044ca8e98183a4cca5e4756913981be64ba7432", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/731d89a6af3c3b70b17864a864b95866848476ec01e3da6637b163c1f9bcbb6ac66c371ff8fb88cfb40ef374d1544782b72e654e0654da4c106aad0dfeabc80d"} 08:52:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:52:00 INFO - Copying logs to upload dir... 08:52:00 INFO - mkdir: /builds/slave/test/build/upload/logs 08:52:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.727284 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-23 08:52:00.311377) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:52:00.314955) ========= 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.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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/c9bd1a981821a49c71e3d0020b60a90c3f5547d4cb6f1722be32b29d7c8486d780d6ba0f170f1f2f8bad85cd2699827e5c3c28f158fd78b33c79eeb2220f3b77", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1092021e2d60aa797e4c72f8a437e3a51460cfb063f0a451519ab080816fa69690f0f9cc5b77e4544b6dd846c6bf270c8c8cc07a354b3399ca43587695ecae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee50418c419994ea9215b0de2eae0fbbc285918251904bdf5b3d3d3c508de6d0fea355183b69d60ff79c74c6b044ca8e98183a4cca5e4756913981be64ba7432", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/731d89a6af3c3b70b17864a864b95866848476ec01e3da6637b163c1f9bcbb6ac66c371ff8fb88cfb40ef374d1544782b72e654e0654da4c106aad0dfeabc80d"} build_url:https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011103 build_url: 'https://queue.taskcluster.net/v1/task/E6B-pf5fSIuylLwKvvHlQQ/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/c9bd1a981821a49c71e3d0020b60a90c3f5547d4cb6f1722be32b29d7c8486d780d6ba0f170f1f2f8bad85cd2699827e5c3c28f158fd78b33c79eeb2220f3b77", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1092021e2d60aa797e4c72f8a437e3a51460cfb063f0a451519ab080816fa69690f0f9cc5b77e4544b6dd846c6bf270c8c8cc07a354b3399ca43587695ecae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee50418c419994ea9215b0de2eae0fbbc285918251904bdf5b3d3d3c508de6d0fea355183b69d60ff79c74c6b044ca8e98183a4cca5e4756913981be64ba7432", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/731d89a6af3c3b70b17864a864b95866848476ec01e3da6637b163c1f9bcbb6ac66c371ff8fb88cfb40ef374d1544782b72e654e0654da4c106aad0dfeabc80d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:52:00.349547) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:52:00.349874) ========= 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.sFYnjus2RE/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.kOQacvlmbB/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.005122 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:52:00.398607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:52:00.398927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:52:00.399270) ========= ========= Total master_lag: 0.18 =========