builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0070 starttime: 1493811874.51 results: success (0) buildid: 20170503030212 builduid: 3d6bc241d5a6429b877fc9b13c38f6b1 revision: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.506266) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.506764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.507035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.534296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.534652) ========= 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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-05-03 04:44:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.8M=0.001s 2017-05-03 04:44:34 (15.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.714845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.715157) ========= 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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.027709 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.764972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:34.765313) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 --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 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-05-03 04:44:34,862 truncating revision to first 12 chars 2017-05-03 04:44:34,862 Setting DEBUG logging. 2017-05-03 04:44:34,862 attempt 1/10 2017-05-03 04:44:34,862 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/82c2d17e74ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 04:44:35,136 unpacking tar archive at: mozilla-central-82c2d17e74ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541317 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:35.330897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:35.331198) ========= script_repo_revision: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:35.331596) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:35.331875) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:35.352448) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-03 04:44:35.352845) ========= /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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:44:35 INFO - MultiFileLogger online at 20170503 04:44:35 in /builds/slave/test 04:44:35 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 04:44:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:35 INFO - {'append_to_log': False, 04:44:35 INFO - 'base_work_dir': '/builds/slave/test', 04:44:35 INFO - 'blob_upload_branch': 'Firefox', 04:44:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:35 INFO - 'branch': 'Firefox', 04:44:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:35 INFO - 'config_files': ('talos/mac_config.py',), 04:44:35 INFO - 'default_actions': ('clobber', 04:44:35 INFO - 'read-buildbot-config', 04:44:35 INFO - 'download-and-extract', 04:44:35 INFO - 'populate-webroot', 04:44:35 INFO - 'create-virtualenv', 04:44:35 INFO - 'install', 04:44:35 INFO - 'run-tests'), 04:44:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:35 INFO - 'download_minidump_stackwalk': True, 04:44:35 INFO - 'download_symbols': 'ondemand', 04:44:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:35 INFO - 'gecko_profile': False, 04:44:35 INFO - 'gecko_profile_interval': 0, 04:44:35 INFO - 'installer_path': 'installer.exe', 04:44:35 INFO - 'log_level': 'info', 04:44:35 INFO - 'log_name': 'talos', 04:44:35 INFO - 'log_to_console': True, 04:44:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:35 INFO - 'opt_config_files': (), 04:44:35 INFO - 'pip_index': False, 04:44:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:35 INFO - 'cmd': ('bash', 04:44:35 INFO - '-c', 04:44:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:35 INFO - 'enabled': True, 04:44:35 INFO - 'halt_on_failure': False, 04:44:35 INFO - 'name': 'check_screen_resolution'},), 04:44:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:35 INFO - 'cmd': ('bash', 04:44:35 INFO - '-c', 04:44:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:35 INFO - 'enabled': True, 04:44:35 INFO - 'halt_on_failure': False, 04:44:35 INFO - 'name': 'check_screen_resolution'},), 04:44:35 INFO - 'run_cmd_checks_enabled': True, 04:44:35 INFO - 'suite': 'dromaeojs-e10s', 04:44:35 INFO - 'system_bits': '32', 04:44:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:35 INFO - 'title': 't-yosemite-r7-0070', 04:44:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:35 INFO - 'use_talos_json': True, 04:44:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:35 INFO - 'work_dir': 'build'} 04:44:35 INFO - [mozharness: 2017-05-03 11:44:35.564894Z] Running clobber step. 04:44:35 INFO - Running pre-action listener: _resource_record_pre_action 04:44:35 INFO - Running main action method: clobber 04:44:35 INFO - rmtree: /builds/slave/test/build 04:44:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:38 INFO - Running post-action listener: _resource_record_post_action 04:44:38 INFO - [mozharness: 2017-05-03 11:44:38.228769Z] Finished clobber step (success) 04:44:38 INFO - [mozharness: 2017-05-03 11:44:38.228894Z] Running read-buildbot-config step. 04:44:38 INFO - Running pre-action listener: _resource_record_pre_action 04:44:38 INFO - Running main action method: read_buildbot_config 04:44:38 INFO - Using buildbot properties: 04:44:38 INFO - { 04:44:38 INFO - "project": "", 04:44:38 INFO - "product": "firefox", 04:44:38 INFO - "script_repo_revision": "production", 04:44:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:44:38 INFO - "repository": "", 04:44:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:44:38 INFO - "buildid": "20170503030212", 04:44:38 INFO - "stage_platform": "macosx64", 04:44:38 INFO - "basedir": "/builds/slave/test", 04:44:38 INFO - "nightly_build": "True", 04:44:38 INFO - "buildnumber": 60, 04:44:38 INFO - "slavename": "t-yosemite-r7-0070", 04:44:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:44:38 INFO - "platform": "yosemite_r7", 04:44:38 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:38 INFO - "branch": "mozilla-central", 04:44:38 INFO - "repo_path": "mozilla-central", 04:44:38 INFO - "revision": "82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4", 04:44:38 INFO - "pgo_build": "False", 04:44:38 INFO - "builduid": "3d6bc241d5a6429b877fc9b13c38f6b1", 04:44:38 INFO - "slavebuilddir": "test" 04:44:38 INFO - } 04:44:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:44:38 INFO - Running post-action listener: _resource_record_post_action 04:44:38 INFO - [mozharness: 2017-05-03 11:44:38.232578Z] Finished read-buildbot-config step (success) 04:44:38 INFO - [mozharness: 2017-05-03 11:44:38.232695Z] Running download-and-extract step. 04:44:38 INFO - Running pre-action listener: _resource_record_pre_action 04:44:38 INFO - Running main action method: download_and_extract 04:44:38 INFO - mkdir: /builds/slave/test/build/tests 04:44:38 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')]} 04:44:38 INFO - trying https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:38 INFO - Downloading https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/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 04:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/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 04:44:39 INFO - Downloaded 1325 bytes. 04:44:39 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:39 INFO - Using the following test package requirements: 04:44:39 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:44:39 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:39 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:39 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:44:39 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'jsshell-mac.zip'], 04:44:39 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:44:39 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:39 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:44:39 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:44:39 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:44:39 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:39 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:39 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:39 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:40 INFO - Content-Length response header: 52700035 04:44:40 INFO - Bytes received: 52700035 04:44:57 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 04:44:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:57 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:59 INFO - Content-Length response header: 52700035 04:44:59 INFO - Bytes received: 52700035 04:45:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:45:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:45:15 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:45:16 INFO - Content-Length response header: 14408811 04:45:16 INFO - Bytes received: 14408811 04:45: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')]} 04:45:16 INFO - trying https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:16 INFO - Downloading https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/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 04:45:18 INFO - Downloaded 71273714 bytes. 04:45:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:18 INFO - mkdir: /builds/slave/test/properties 04:45:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:18 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:18 INFO - Contents: 04:45:18 INFO - build_url:https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:20 INFO - Running post-action listener: _resource_record_post_action 04:45:20 INFO - Running post-action listener: set_extra_try_arguments 04:45:20 INFO - [mozharness: 2017-05-03 11:45:20.006302Z] Finished download-and-extract step (success) 04:45:20 INFO - [mozharness: 2017-05-03 11:45:20.006397Z] Running populate-webroot step. 04:45:20 INFO - Running pre-action listener: _resource_record_pre_action 04:45:20 INFO - Running main action method: populate_webroot 04:45:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:20 INFO - '--tppagecycles', 04:45:20 INFO - '7'], 04:45:20 INFO - 'tests': ['tsvgm']}}, 04:45:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['damp', 'tps']}, 04:45:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'tests': ['damp', 'tps']}, 04:45:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['dromaeo_dom']}, 04:45:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:45:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:45:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['a11yr', 04:45:20 INFO - 'ts_paint', 04:45:20 INFO - 'tpaint', 04:45:20 INFO - 'sessionrestore', 04:45:20 INFO - 'sessionrestore_no_auto_restore', 04:45:20 INFO - 'tabpaint']}, 04:45:20 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:45:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:45:20 INFO - 'bloom_basic_ref']}, 04:45:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['tsvgx', 04:45:20 INFO - 'tsvgr_opacity', 04:45:20 INFO - 'tart', 04:45:20 INFO - 'tscrollx', 04:45:20 INFO - 'cart', 04:45:20 INFO - 'tsvg_static']}, 04:45:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:20 INFO - 'tsvgr_opacity', 04:45:20 INFO - 'tart', 04:45:20 INFO - 'tscrollx', 04:45:20 INFO - 'cart', 04:45:20 INFO - 'tsvg_static']}, 04:45:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'talos_options': ['--disable-e10s'], 04:45:20 INFO - 'tests': ['tp5o']}, 04:45:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:45:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'talos_options': ['--disable-e10s', 04:45:20 INFO - '--xperf_path', 04:45:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:20 INFO - 'tests': ['tp5n']}, 04:45:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:45:20 INFO - 'talos_options': ['--xperf_path', 04:45:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:20 INFO - 'tests': ['tp5n']}}, 04:45:20 INFO - 'talos.zip': {'path': '', 04:45:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:20 INFO - Running post-action listener: _resource_record_post_action 04:45:20 INFO - [mozharness: 2017-05-03 11:45:20.025610Z] Finished populate-webroot step (success) 04:45:20 INFO - [mozharness: 2017-05-03 11:45:20.025750Z] Running create-virtualenv step. 04:45:20 INFO - Running pre-action listener: _resource_record_pre_action 04:45:20 INFO - Running main action method: create_virtualenv 04:45:20 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:20 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 04:45:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:20 INFO - Using real prefix '/tools/python27' 04:45:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:20 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:23 INFO - Installing pip.................done. 04:45:23 INFO - Return code: 0 04:45:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:24 INFO - Reading from file tmpfile_stdout 04:45:24 INFO - Output received: 04:45:24 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) 04:45:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:24 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')]} 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 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')]} 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:24 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 04:45:24 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 04:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:24 INFO - 'HOME': '/Users/cltbld', 04:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:24 INFO - 'LOGNAME': 'cltbld', 04:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:24 INFO - 'MOZ_NO_REMOTE': '1', 04:45:24 INFO - 'NO_EM_RESTART': '1', 04:45:24 INFO - 'PAGER': '/bin/cat', 04:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:24 INFO - 'PWD': '/builds/slave/test', 04:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:24 INFO - 'SHELL': '/bin/bash', 04:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:24 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:24 INFO - 'USER': 'cltbld', 04:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:24 INFO - 'XPC_FLAGS': '0x0', 04:45:24 INFO - 'XPC_SERVICE_NAME': '0', 04:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 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 04:45:24 INFO - Cleaning up... 04:45:24 INFO - Return code: 0 04:45:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:24 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')]} 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 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')]} 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:24 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:24 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 04:45:24 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 04:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:24 INFO - 'HOME': '/Users/cltbld', 04:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:24 INFO - 'LOGNAME': 'cltbld', 04:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:24 INFO - 'MOZ_NO_REMOTE': '1', 04:45:24 INFO - 'NO_EM_RESTART': '1', 04:45:24 INFO - 'PAGER': '/bin/cat', 04:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:24 INFO - 'PWD': '/builds/slave/test', 04:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:24 INFO - 'SHELL': '/bin/bash', 04:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:24 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:24 INFO - 'USER': 'cltbld', 04:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:24 INFO - 'XPC_FLAGS': '0x0', 04:45:24 INFO - 'XPC_SERVICE_NAME': '0', 04:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:24 INFO - Downloading/unpacking psutil>=3.1.1 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:26 INFO - Installing collected packages: psutil 04:45:26 INFO - Running setup.py install for psutil 04:45:26 INFO - building 'psutil._psutil_osx' extension 04:45:26 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 04:45: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 04:45: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 04:45: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 04:45:27 INFO - building 'psutil._psutil_posix' extension 04:45: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 04:45:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:27 INFO - ^ 04:45:27 INFO - 1 warning generated. 04:45:27 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 04:45:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:27 INFO - Successfully installed psutil 04:45:27 INFO - Cleaning up... 04:45:28 INFO - Return code: 0 04:45:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45: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 04:45: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 04:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:28 INFO - 'HOME': '/Users/cltbld', 04:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:28 INFO - 'LOGNAME': 'cltbld', 04:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:28 INFO - 'MOZ_NO_REMOTE': '1', 04:45:28 INFO - 'NO_EM_RESTART': '1', 04:45:28 INFO - 'PAGER': '/bin/cat', 04:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:28 INFO - 'PWD': '/builds/slave/test', 04:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:28 INFO - 'SHELL': '/bin/bash', 04:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:28 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:28 INFO - 'USER': 'cltbld', 04:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:28 INFO - 'XPC_FLAGS': '0x0', 04:45:28 INFO - 'XPC_SERVICE_NAME': '0', 04:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:45:29 INFO - Installing collected packages: mozsystemmonitor 04:45:29 INFO - Running setup.py install for mozsystemmonitor 04:45:29 INFO - Successfully installed mozsystemmonitor 04:45:29 INFO - Cleaning up... 04:45:29 INFO - Return code: 0 04:45:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45:29 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')]} 04:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:29 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')]} 04:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:29 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:29 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 04:45:29 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 04:45:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:29 INFO - 'HOME': '/Users/cltbld', 04:45:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:29 INFO - 'LOGNAME': 'cltbld', 04:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:29 INFO - 'MOZ_NO_REMOTE': '1', 04:45:29 INFO - 'NO_EM_RESTART': '1', 04:45:29 INFO - 'PAGER': '/bin/cat', 04:45:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:29 INFO - 'PWD': '/builds/slave/test', 04:45:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:29 INFO - 'SHELL': '/bin/bash', 04:45:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:29 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:29 INFO - 'USER': 'cltbld', 04:45:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:29 INFO - 'XPC_FLAGS': '0x0', 04:45:29 INFO - 'XPC_SERVICE_NAME': '0', 04:45:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:30 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:30 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 04:45:30 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 04:45:30 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 04:45:30 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 04:45:30 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 04:45:30 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 04:45:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:31 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 04:45:31 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 04:45:31 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 04:45:31 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 04:45:31 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 04:45:31 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 04:45:32 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:32 INFO - warning: no files found matching '*.txt' 04:45:32 INFO - no previously-included directories found matching 'build' 04:45:32 INFO - no previously-included directories found matching 'dist' 04:45:32 INFO - no previously-included directories found matching '.git*' 04:45:32 INFO - Installing collected packages: jsonschema, functools32 04:45:32 INFO - Running setup.py install for functools32 04:45:32 INFO - warning: no files found matching '*.txt' 04:45:32 INFO - no previously-included directories found matching 'build' 04:45:32 INFO - no previously-included directories found matching 'dist' 04:45:32 INFO - no previously-included directories found matching '.git*' 04:45:32 INFO - Successfully installed jsonschema functools32 04:45:32 INFO - Cleaning up... 04:45:32 INFO - Return code: 0 04:45:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:45: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 functools32==3.2.3-2 04:45:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:32 INFO - 'HOME': '/Users/cltbld', 04:45:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:32 INFO - 'LOGNAME': 'cltbld', 04:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:32 INFO - 'MOZ_NO_REMOTE': '1', 04:45:32 INFO - 'NO_EM_RESTART': '1', 04:45:32 INFO - 'PAGER': '/bin/cat', 04:45:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:32 INFO - 'PWD': '/builds/slave/test', 04:45:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:32 INFO - 'SHELL': '/bin/bash', 04:45:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:32 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:32 INFO - 'USER': 'cltbld', 04:45:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:32 INFO - 'XPC_FLAGS': '0x0', 04:45:32 INFO - 'XPC_SERVICE_NAME': '0', 04:45:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:33 INFO - Cleaning up... 04:45:33 INFO - Return code: 0 04:45:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:33 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')]} 04:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:33 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')]} 04:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:33 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:33 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 04:45:33 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 04:45:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:33 INFO - 'HOME': '/Users/cltbld', 04:45:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:33 INFO - 'LOGNAME': 'cltbld', 04:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:33 INFO - 'MOZ_NO_REMOTE': '1', 04:45:33 INFO - 'NO_EM_RESTART': '1', 04:45:33 INFO - 'PAGER': '/bin/cat', 04:45:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:33 INFO - 'PWD': '/builds/slave/test', 04:45:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:33 INFO - 'SHELL': '/bin/bash', 04:45:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:33 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:33 INFO - 'USER': 'cltbld', 04:45:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:33 INFO - 'XPC_FLAGS': '0x0', 04:45:33 INFO - 'XPC_SERVICE_NAME': '0', 04:45:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:33 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:33 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 04:45:33 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 04:45:33 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 04:45:33 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 04:45:33 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 04:45:33 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 04:45:34 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:35 INFO - Downloading docopt-0.6.1.tar.gz 04:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:35 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:35 INFO - Running setup.py install for blobuploader 04:45:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:35 INFO - Running setup.py install for requests 04:45:36 INFO - Running setup.py install for docopt 04:45:36 INFO - Successfully installed blobuploader requests docopt 04:45:36 INFO - Cleaning up... 04:45:36 INFO - Return code: 0 04:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:36 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:36 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 04:45:36 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 04:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:36 INFO - 'HOME': '/Users/cltbld', 04:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:36 INFO - 'LOGNAME': 'cltbld', 04:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:36 INFO - 'MOZ_NO_REMOTE': '1', 04:45:36 INFO - 'NO_EM_RESTART': '1', 04:45:36 INFO - 'PAGER': '/bin/cat', 04:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:36 INFO - 'PWD': '/builds/slave/test', 04:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:36 INFO - 'SHELL': '/bin/bash', 04:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:36 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:36 INFO - 'USER': 'cltbld', 04:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:36 INFO - 'XPC_FLAGS': '0x0', 04:45:36 INFO - 'XPC_SERVICE_NAME': '0', 04:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-mzjqBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-YXreQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:36 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Q3W2PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-D7OG_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-EOPIEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-tDfgWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-dSOkXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-U4UqmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zdFtXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-BVon7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-8VJb51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-KzLDbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Ly4k9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-oRRQyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Lt_9Xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-A8XPTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Xhljdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:38 INFO - Running setup.py install for manifestparser 04:45:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:38 INFO - Running setup.py install for mozcrash 04:45:38 INFO - Running setup.py install for mozdebug 04:45:38 INFO - Running setup.py install for mozdevice 04:45:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Running setup.py install for mozfile 04:45:39 INFO - Running setup.py install for mozhttpd 04:45:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Running setup.py install for mozinfo 04:45:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Running setup.py install for mozInstall 04:45:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Running setup.py install for mozleak 04:45:39 INFO - Running setup.py install for mozlog 04:45:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:39 INFO - Running setup.py install for moznetwork 04:45:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:40 INFO - Running setup.py install for mozprocess 04:45:40 INFO - Running setup.py install for mozprofile 04:45:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:40 INFO - Running setup.py install for mozrunner 04:45:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:40 INFO - Running setup.py install for mozscreenshot 04:45:40 INFO - Running setup.py install for moztest 04:45:40 INFO - Running setup.py install for mozversion 04:45:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:41 INFO - Cleaning up... 04:45:41 INFO - Return code: 0 04:45:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:41 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:41 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 04:45:41 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 04:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:41 INFO - 'HOME': '/Users/cltbld', 04:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:41 INFO - 'LOGNAME': 'cltbld', 04:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:41 INFO - 'MOZ_NO_REMOTE': '1', 04:45:41 INFO - 'NO_EM_RESTART': '1', 04:45:41 INFO - 'PAGER': '/bin/cat', 04:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:41 INFO - 'PWD': '/builds/slave/test', 04:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:41 INFO - 'SHELL': '/bin/bash', 04:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:41 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:41 INFO - 'USER': 'cltbld', 04:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:41 INFO - 'XPC_FLAGS': '0x0', 04:45:41 INFO - 'XPC_SERVICE_NAME': '0', 04:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ayi74q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:41 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-uQHjmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:41 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-FED3mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:41 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_Gg8F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-yKnH8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:41 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ZQzuqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:41 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)) 04:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:41 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ZQpAjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SBdzPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OXNKNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aA3rsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DhLaeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-TLzV4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-e2HqhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-33Qcuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Cqlrcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:42 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)) 04:45:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ETeiam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:43 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)) 04:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-X40llC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:43 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)) 04:45:43 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)) 04:45:43 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)) 04:45:43 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)) 04:45:43 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:43 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:44 INFO - Downloading blessings-1.6.tar.gz 04:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:44 INFO - Installing collected packages: blessings 04:45:44 INFO - Running setup.py install for blessings 04:45:44 INFO - Successfully installed blessings 04:45:44 INFO - Cleaning up... 04:45:44 INFO - Return code: 0 04:45:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:45 INFO - Reading from file tmpfile_stdout 04:45:45 INFO - Current package versions: 04:45:45 INFO - blessings == 1.6 04:45:45 INFO - blobuploader == 1.2.4 04:45:45 INFO - docopt == 0.6.1 04:45:45 INFO - functools32 == 3.2.3-2 04:45:45 INFO - jsonschema == 2.5.1 04:45:45 INFO - manifestparser == 1.1 04:45:45 INFO - mozInstall == 1.12 04:45:45 INFO - mozcrash == 1.0 04:45:45 INFO - mozdebug == 0.1 04:45:45 INFO - mozdevice == 0.50 04:45:45 INFO - mozfile == 1.2 04:45:45 INFO - mozhttpd == 0.7 04:45:45 INFO - mozinfo == 0.9 04:45:45 INFO - mozleak == 0.1 04:45:45 INFO - mozlog == 3.4 04:45:45 INFO - moznetwork == 0.27 04:45:45 INFO - mozprocess == 0.25 04:45:45 INFO - mozprofile == 0.28 04:45:45 INFO - mozrunner == 6.13 04:45:45 INFO - mozscreenshot == 0.1 04:45:45 INFO - mozsystemmonitor == 0.3 04:45:45 INFO - moztest == 0.8 04:45:45 INFO - mozversion == 1.4 04:45:45 INFO - psutil == 3.1.1 04:45:45 INFO - requests == 1.2.3 04:45:45 INFO - wsgiref == 0.1.2 04:45:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:45 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:45 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 04:45:45 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 04:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:45 INFO - 'HOME': '/Users/cltbld', 04:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:45 INFO - 'LOGNAME': 'cltbld', 04:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:45 INFO - 'MOZ_NO_REMOTE': '1', 04:45:45 INFO - 'NO_EM_RESTART': '1', 04:45:45 INFO - 'PAGER': '/bin/cat', 04:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:45 INFO - 'PWD': '/builds/slave/test', 04:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:45 INFO - 'SHELL': '/bin/bash', 04:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:45 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:45 INFO - 'USER': 'cltbld', 04:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:45 INFO - 'XPC_FLAGS': '0x0', 04:45:45 INFO - 'XPC_SERVICE_NAME': '0', 04:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 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)) 04:45:45 INFO - Cleaning up... 04:45:45 INFO - Return code: 0 04:45:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:45 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 0x1035aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb283dda000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, '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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 04:45:45 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 04:45:45 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 04:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:45 INFO - 'HOME': '/Users/cltbld', 04:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:45 INFO - 'LOGNAME': 'cltbld', 04:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:45 INFO - 'MOZ_NO_REMOTE': '1', 04:45:45 INFO - 'NO_EM_RESTART': '1', 04:45:45 INFO - 'PAGER': '/bin/cat', 04:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:45 INFO - 'PWD': '/builds/slave/test', 04:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:45 INFO - 'SHELL': '/bin/bash', 04:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:45:45 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:45 INFO - 'USER': 'cltbld', 04:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:45 INFO - 'XPC_FLAGS': '0x0', 04:45:45 INFO - 'XPC_SERVICE_NAME': '0', 04:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:45 INFO - Cleaning up... 04:45:45 INFO - Return code: 0 04:45:45 INFO - Running post-action listener: _resource_record_post_action 04:45:45 INFO - Running post-action listener: _start_resource_monitoring 04:45:45 INFO - Starting resource monitoring. 04:45:45 INFO - [mozharness: 2017-05-03 11:45:45.612175Z] Finished create-virtualenv step (success) 04:45:45 INFO - [mozharness: 2017-05-03 11:45:45.613182Z] Running install step. 04:45:45 INFO - Running pre-action listener: _resource_record_pre_action 04:45:45 INFO - Running main action method: install 04:45:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:45 INFO - Reading from file tmpfile_stdout 04:45:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:45 INFO - Reading from file tmpfile_stdout 04:45:45 INFO - Output received: 04:45:45 INFO - Usage: mozinstall [options] installer 04:45:45 INFO - Options: 04:45:45 INFO - -h, --help show this help message and exit 04:45:45 INFO - -d DEST, --destination=DEST 04:45:45 INFO - Directory to install application into. [default: 04:45:45 INFO - "/builds/slave/test"] 04:45:45 INFO - --app=APP Application being installed. [default: firefox] 04:45:45 INFO - mkdir: /builds/slave/test/build/application 04:45:45 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'] 04:45:45 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 04:46:05 INFO - Reading from file tmpfile_stdout 04:46:05 INFO - Output received: 04:46:05 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:46:05 INFO - Running post-action listener: _resource_record_post_action 04:46:05 INFO - [mozharness: 2017-05-03 11:46:05.673738Z] Finished install step (success) 04:46:05 INFO - [mozharness: 2017-05-03 11:46:05.673877Z] Running run-tests step. 04:46:05 INFO - Running pre-action listener: _resource_record_pre_action 04:46:05 INFO - Running main action method: run_tests 04:46:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:05 INFO - 2017-05-03 04:46:05.688 screenresolution[1624:7385] starting screenresolution argv=screenresolution get 04:46:05 INFO - 2017-05-03 04:46:05.691 screenresolution[1624:7385] Display 0: 1600x1200x32@60 04:46:05 INFO - 2017-05-03 04:46:05.696 screenresolution[1625:7388] starting screenresolution argv=screenresolution list 04:46:05 INFO - Available Modes on Display 0 04:46:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:05 INFO - Intel Iris: 04:46:05 INFO - Chipset Model: Intel Iris 04:46:05 INFO - Type: GPU 04:46:05 INFO - Bus: Built-In 04:46:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:05 INFO - Vendor: Intel (0x8086) 04:46:05 INFO - Device ID: 0x0a2e 04:46:05 INFO - Revision ID: 0x0009 04:46:05 INFO - Displays: 04:46:05 INFO - Display: 04:46:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:05 INFO - Display Serial Number: 1600x1200 60 04:46:05 INFO - Main Display: Yes 04:46:05 INFO - Mirror: Off 04:46:05 INFO - Online: Yes 04:46:05 INFO - Rotation: Supported 04:46:05 INFO - Return code: 0 04:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:05 INFO - Python 2.7.3 04:46:05 INFO - Return code: 0 04:46:05 INFO - grabbing minidump binary from tooltool 04:46:05 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')]} 04:46:05 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 0x103388d30>, '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 0x1032aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10337dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:05 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 04:46:05 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 04:46:05 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 04:46:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:06 INFO - Return code: 0 04:46:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - ENV: RUST_BACKTRACE is now 1 04:46:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 04:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 04:46:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JTuNQVFl7U/Render', 04:46:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:06 INFO - 'HOME': '/Users/cltbld', 04:46:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:06 INFO - 'LOGNAME': 'cltbld', 04:46:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:06 INFO - 'MOZ_NO_REMOTE': '1', 04:46:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:06 INFO - 'NO_EM_RESTART': '1', 04:46:06 INFO - 'PAGER': '/bin/cat', 04:46:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:06 INFO - 'PWD': '/builds/slave/test', 04:46:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:06 INFO - 'RUST_BACKTRACE': '1', 04:46:06 INFO - 'SHELL': '/bin/bash', 04:46:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DXHjLLMpzy/Listeners', 04:46:06 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 04:46:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:06 INFO - 'USER': 'cltbld', 04:46:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:06 INFO - 'XPC_FLAGS': '0x0', 04:46:06 INFO - 'XPC_SERVICE_NAME': '0', 04:46:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 04:46:06 INFO - mozversion application_buildid: 20170503030212 04:46:06 INFO - mozversion application_changeset: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 04:46:06 INFO - mozversion application_display_name: Nightly 04:46:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:06 INFO - mozversion application_name: Firefox 04:46:06 INFO - mozversion application_remotingname: firefox 04:46:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:06 INFO - mozversion application_vendor: Mozilla 04:46:06 INFO - mozversion application_version: 55.0a1 04:46:06 INFO - mozversion platform_buildid: 20170503030212 04:46:06 INFO - mozversion platform_changeset: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 04:46:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:06 INFO - mozversion platform_version: 55.0a1 04:46:06 INFO - using testdate: 1493811966 04:46:06 INFO - actual date: 1493811966 04:46:06 INFO - starting webserver on 'localhost:49240' 04:46:06 INFO - SUITE-START | Running 2 tests 04:46:06 INFO - TEST-START | dromaeo_css 04:46:06 INFO - operating with platform_type : mac_ 04:46:06 INFO - Initialising browser for dromaeo_css test... 04:46:06 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpRNP5Ji/profile http://localhost:49240/getInfo.html) 04:46:08 INFO - PID 1635 | 2017-05-03 04:46:08.919 plugin-container[1638:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 04:46:08 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:46:08 INFO - PID 1635 | 2017-05-03 04:46:08.921 plugin-container[1638:7578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.CFPasteboardClient' 04:46:08 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:46:08 INFO - PID 1635 | 2017-05-03 04:46:08.921 plugin-container[1638:7578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:46:09 INFO - PID 1635 | __metrics Screen width/height:1600/1200 04:46:09 INFO - PID 1635 | colorDepth:24 04:46:09 INFO - PID 1635 | Browser inner width/height: 1024/691 04:46:09 INFO - PID 1635 | __metrics 04:46:10 INFO - TEST-INFO | 1635: exit 0 04:46:10 INFO - Browser initialized. 04:46:10 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:10 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpRNP5Ji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:12 INFO - PID 1639 | 2017-05-03 04:46:12.534 plugin-container[1640:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:46:12 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:46:12 INFO - PID 1639 | 2017-05-03 04:46:12.535 plugin-container[1640:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:46:12 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:46:12 INFO - PID 1639 | 2017-05-03 04:46:12.535 plugin-container[1640:7712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:46:22 INFO - PID 1639 | RSS: Main: 250904576 04:46:22 INFO - PID 1639 | 04:49:25 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:49:27 INFO - PID 1639 | RSS: Main: 253923328 04:49:27 INFO - PID 1639 | 04:52:29 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:52:30 INFO - PID 1639 | RSS: Main: 256319488 04:52:30 INFO - PID 1639 | 04:55:31 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:55:32 INFO - PID 1639 | RSS: Main: 269008896 04:55:32 INFO - PID 1639 | 04:58:34 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:58:34 INFO - PID 1639 | RSS: Main: 261992448 04:58:34 INFO - PID 1639 | 05:01:37 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:01:38 INFO - PID 1639 | RSS: Main: 266350592 05:01:38 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:04:40 INFO - PID 1639 | RSS: Main: 265043968 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | __start_tp_report 05:04:40 INFO - PID 1639 | _x_x_mozilla_page_load 05:04:40 INFO - PID 1639 | _x_x_mozilla_page_load_details 05:04:40 INFO - PID 1639 | |i|pagename|runs| 05:04:40 INFO - PID 1639 | |0;dojo.html;2882.279430142464;2930.886263219976;3537.893863184104;3571.2678900825335;3617.3307938031467;5662.207290979261;6122.204113465244;6207.627542347463;6224.284207316155;6228.781992630254;6127.739336776409;6143.573288551031;6155.846103847406;6225.564210505267;6232.968835155818;5250.923560670053;5334.946650533503;5443.700596467193;5484.122540393528;5511.614187006911;4126.112885729567;5589.524890384322;5604.971975140119;5618.4662457066515;5633.943660563404;5420.403755586883;5460.180972854064;5500.312460942382;5502.80740174092;5525.11598144299;5488.7530061147345;5544.445555444464;5547.750351234205;5563.998480273549;5574.219609254707;5395.649282796606;5441.047816632113;5448.754806033738;5476.808311709111;5505.476979686923;6135.447809697104;6137.631742095488;6201.503879689614;6215.409536094064;6233.937660623426;6435.259945106326;6505.544611877171;6596.373344532261;6634.734610615618;6655.301193374675;6812.557183783038;6813.386795188408;6813.659317034128;6814.2845001274645;6828.078209441712;6214.937850621526;6245.969415046512;6288.496920246369;6316.052592111174;6318.526110541728;5503.697296648643;5974.223350964434;6644.734210631521;6674.199096108498;6774;5442.782288708487;6316.242050953915;6393.7762178324465;6485.4163125318;6604.3395660434535;5400.837974860682;6423.907935650951;6631.469482441393;6676.899846502306;6742.932570674328;6058.878822423527;6647.634380109141;6654.367835055662;6665.533412661067;6671.966640166768;6405.391487808651;6484.416402523795;6588.374104460068;6692.866142677217;6699.162604674416;4678.391809064799;4734.668573199843;5294.285271488377;5347.304850369427;5371.409144994048;3728.794916279631;4459.576340247671;4702.012577358724;4726.810927562928;4786.2820576913045;5175.585953123817;6162.352952939967;6175.536834737412;6311.779087731908;6335.334789847;6671.833204169838;6736.292689267654;6749.831254218586;6780.627065511346;6835.350641689031;6294;6307.684615769285;6312.684365781784;6314.810555683337;6328.968355158195;5676.120201368794;5978.342382337939;5980.192673989043;5981.581289309706;6000.669963151981;6814.863702725917;6825.897611535732;6879.174499060145;6973.58158510491;7005.0893383859775;6761.053452516553;6761.797146085625;6779.762708305186;6811.489138314547;6814.625195614289;6914.377706006484;6964.199117101598;6994.195667498099;6994.370506654425;7025.824354391181;6405.903911441239;6542.967285163544;6562.737490500326;6684.197896252481;6707.5975441473665;6667.49993750461;6773.390394864486;6782.796516104525;6794.626295553792;6821.488388370992;6334.9366506334345;6406;6519.576227545194;6565.967170164118;6586.934130658632;6471.546991710535;6562.737490500517;6571.769988050395;6586.571872828251;6591.703373348307;5388.461153884737;6194.163787888578;6379.872402552016;6440;6458.321876203119;6367.331430199948;6368.395002474757;6474.4820414365795;6498.772542960974;6534.803955881332;6364.331745166876;6379.6810159490915;6380.649064301508;6381.042843573501;6400 05:04:40 INFO - PID 1639 | |1;ext.html;746.0934964018716;791.2917938445091;820.470796336363;1185.063799598318;1264.7344057747873;37355.813220933895;38312.04219894497;38347.424788628196;38352;38386.61613383865;38367.61632383675;38570;38650.61349386505;38688;38722.83831485053;7902.446828721992;8033.95983020087;8310.376721745894;8508.489490630582;8560.700375486858;8367.790805229888;8443.324534037292;8472.406931514797;8476.745697629081;8479.321654267644;8118.837623247531;8411.15888411157;8483.406161568693;8484.406091573592;8500.404971651988;7536.924630753677;8427.789305267355;8446.662133514652;8453.408261421702;8474.491530508127;7532.887006694904;8042.718504852302;8412.495250285003;8417.621207045697;8430.747077587683;37521.81239093815;37740.62259377399;37744;37818.43272350917;37878.43182352267;3650.653187947141;3655.067957670777;3690.9261814763554;3692.058525076115;3695.9630403696156;30772.384552309053;31043.689563104304;31083.533746993813;31155.221119471968;31164.53253201204;3140.5289206618963;3173.984130079358;3189.5375170600364;3192.3296107817387;3203.0390882735087;289018;289848;300065;307875.9999999978;308973;48183.27725084127;48235.75882120567;48322.516774832504;48338.75830620895;48414.27378589324;50383.24425133626;50461.74769126204;53711.19433208505;54578;54721.90556188933;14283.714325713427;14441.061331013661;14441.133531988115;14457.421703131757;14492;11448.141389395829;11519.596814111634;11540.307581545134;11558.075353971662;11582.594609188638;13980.72038559223;13984.440622375194;13990.30048497571;13994.88040956721;14053.718925621635;5211.635185536976;6158.753649854045;6175;6183.165272688129;6197.628142311476;870.6822009966317;917.5320586500933;1017.7150397888603;1020.938224246775;1057.9735506612396;21829.454263643536;24861.254162375157;24983.37541561439;25022.99908003696;25093.87453062723;1125.5272785430138;1129.4635048352131;1150.465033759306;1163.3892206591606;1163.627639155479;12515.249085054744;12680.74638507243;12783.488660453664;12808.423620937081;12811.476843868011;337616;341053.88419173734;341525;342352;342380;54442;54606.904482164;54963;55028.449715503135;55096;1370.9725805484034;1502.7370210213169;1535.1556643846063;1540.3453532248973;1556.820965588949;22350.552988940457;24376.51246975086;24398;24565.877170614032;24579.508409832062;38252.617473826016;38450.61549384582;38463.423048654855;38491.42262866115;38861.417078743274;292636;292798;292883;293362;293811;34722.826385867906;35992.820035899655;36380.81809590935;36741.08147296339;36932.07669808276;45037.77481112573;45797.54202458066;46715.532844672474;46931;46931;30977.225569360948;31009;31834.044978650676;31905.042848714576;32304;23829.165979190646;24899.87550062238;30849.845750772;31073.26972407884;32056.83971580127;37468.6253137476;37951.620483795916;37980.24039519139;37981.24037519179;38072.238555228185;23925.880370598035;24930.626040609768;28077.71922280751;31237.375252495276;32167.356652866343;20272.087756050718;23673.816309184127;24867.378315542257;24974.875125624254;24986.62520062237 05:04:40 INFO - PID 1639 | |2;jquery.html;407.2425288962529;426.5947350017484;431.6827132057937;435.18185810675925;461.30573486902205;16546;17085.743713844273;21029.948502574887;21849.781502185015;21859.890700546493;21963.6705449418;22086.226982055632;22241.888790556026;22308.21921232757;22469;5951.375105613914;6159.630422174684;6177.320494745574;6195.535334849882;6198.256209254896;5935.080062590306;6041.667708276043;6095.7561697532265;6153.538484613672;6159.907601385983;5460.808871689492;5862.534366408398;6092.573519853612;6107;6117.143599895996;5852.561057920651;6008.789692360769;6046.546509011841;6089.299730531001;6092.086187071908;5223.921641175385;5364.383095943977;5403.162509810985;5405.675659460445;5410.621256512046;24978.625320620205;26863;27000.729992700213;27021.864890675617;27175.86412067927;2225.810806081485;2245.9663105053437;2246.472079061419;2271.5911135995516;2272.9772702273094;14645.19451430171;16907.985520868788;17529.824701752947;17913.19390627425;18047.819521804875;2112.8732276063483;2162.0811155258953;2207.3377986603955;2210.5689390568846;2213.966790498144;145141;145405.5475872616;145828;146737;147910.52089479074;11834.644960651194;12335.211394347769;12339.13626046186;12379.442925068393;12383.938080309541;15823.604409889846;16035.599110022109;16070.43753468623;16088.75866861998;16096.67806643884;8727.476351418934;8915.28677705782;8954.246420385127;8966.68616598416;8982.101789821096;8517.829643407098;8530.70142545008;8550.871736923951;8703.6518539259;8704.086070962585;7508.699652013967;7654.617269136521;7675.194104618935;7676.462647614725;7683.731069412655;2109.6624540073512;2127.8084972352563;2128.7764784697692;2130.1053557505884;2139.28334008109;949.5501605856873;968.941863488193;984.7735020945223;1022.1209759606733;1035.6478797208986;20681.89659051695;21091.367258982256;21187;21374;25675.871620641778;1002.1231422505308;1015.2791518022287;1028.9742756431149;1032.230987914009;1036.160709825044;10226.693199204035;11043.171762117874;12995.935020324838;13908;14018;526338;545173;558520;570633;578565;137509;138498;138886;139908.6009139916;140361.29819350838;1187.0859438232512;1214.3988725580718;1241.7019915220462;1243.384524660299;1246.4141853328922;16857.21855893764;20626.793732062488;20679;21132;21720;44286.33570496381;48688.782780430774;52188.478115218364;54301;60044.39955600388;79368.60315698385;79392;80623.79064314158;81218.59390703008;81656;7337.26627337262;8794.120587941155;8878.5116818573;8938;9041.638334466548;14737.852621473648;14744.926275368554;14747.483838065617;15134.697306053597;15241.237938103272;10432.843507347548;12005.279683219034;12024.699382515508;12155.756884862076;12192.146549741434;10871.891281087088;11546.94226528862;11617.302961822317;11786.528538858349;12032;10843.186760993114;11808.350540720343;12220.938895305466;12221.450034748279;12545.121841471375;8015.236647937444;8757.47455152693;8769.930524316438;8869.512176830078;9225.538723063686;10365;11930.284182949052;11965.820512692491;12196.451159698012;12251.448684809027 05:04:40 INFO - PID 1639 | |3;mootools.html;1219.9512019519218;1423.3452611798573;1442.4807069455;1456.8543145685433;1462.341946124244;11312.208145429824;11755.941220293907;11820.645380638573;11853.822192667107;12018.759624807499;11758.706032349195;11928.64214073577;11949.103817213721;11949.283043017425;11950.462229199686;2518.8866501007406;2551.566233740259;2561.7566331198504;2568.7816535594498;2571.3828681116493;2476.1333533263396;2524.0408644715076;2525.785308248801;2538.9746102539016;2547.1594373856674;2503.5994240921455;2510.083819405913;2543.2370288913344;2550.7831834294107;2555.6038813983864;2466.3587467258553;2482.329770961839;2509.0841842727455;2510.7614776596192;2515.8490490570534;2419.879006049708;2502.199296225209;2502.587073132931;2513.283714141461;2516.8112391570708;11728.765424691544;11804.881951180465;11894.464749086312;11904.58333958316;11927.284362938164;6505.674716264215;6533.431976325695;6608.107905432753;6858.588484690935;6879.208890977551;4267.579517900436;4346.869593912188;4468.436047383422;4487.685861989662;4556.840510582147;1258.8867001969866;1269.6668498076983;1288.3493835613049;1317.55203230901;1318.294712328909;15707.528774136794;16192.595185120348;16490.010599363955;16625.584360390967;16849.07330096844;5336.519713225828;5351.705656188947;5560.582956278296;5658.405867383948;5751.309842818805;10874.401907894984;10985.945070274758;11795.35125568085;11910.106741994385;11923.761524769456;6530.967345163243;6548.116004339448;6567.704453299613;6592.472602191813;6607.339266073451;5951.791687291011;6090.573659843857;6244.438000579969;6246.46905013079;6252.906206406908;4277.358396240526;4394.835368627317;4416.955830441654;4445.911081778347;4458.71018383804;1255.8869701726887;1277.9424925878359;1279.456231101778;1292.3150730113055;1299.0841456773142;736.5617457612711;777.1412589088975;789.5302295134386;815.6941147069849;821.4742564758559;6688.598684078971;6993.2657071007825;7031.578105313698;7034.155901291878;7109.608971506616;847.855864503036;906.3564868943124;913.3423934766957;914.6387177065022;915.4187091197005;4041.333180025322;4244.639205667491;4368.9126217476105;4455;4462.129884672458;14793.852061479247;15159.393624255126;15269.236538173047;15298.847011529742;15326.846731532542;14605.634859128395;15086.849131508545;15158.469453569072;15172.089674619558;15201.467948621745;6420.325865784119;8577.4424662398;8744.169303916118;8751;8768.123187681182;5962.4633782959745;6231.158793562811;7045.365917067488;7083.362497375261;7138;13294.468221271041;13374.465021399035;13531.120477168952;13809.171449713049;13997;13682.931585342409;13834.515791947233;14019.789703154256;14182.361793719514;14243.145411275356;7330.5235159716585;8597.742067737978;8654.22112009922;8668.479891206547;8672.566371681516;10935.28920620157;11026.44867756593;11027.944860275647;11030.172737044593;11041.44792760375;8950.14973577509;9280.767980800263;9333.020032896447;9339.673111441067;9372.625094996123;9115.498647574183;9120.954395227982;9162.312826537904;9185.724428267162;9214.677486287948;8211.58942052907;8373.120822313569;8381.11998240176;8386.412951093609;8400.24397804176;5385.38068122171;5769.91345129815;5931.792387266598;6085.9695701521205;6215.285242197205;5765;6074.331823499323;6094.329623741473;6200.0779758275075;6378.298387177314 05:04:40 INFO - PID 1639 | |4;prototype.html;360.3225935241745;388.32237745134745;410.730971213855;413.7248729594818;416.8728537795973;5351.879053129088;6296.338884417139;6296.937030629692;6439.388258115483;6517.315681853408;4784.5693887550115;6158.599691020092;6175.413335733109;6278.403551662596;6285.779997700081;4524.163029839474;4571.3828633134435;4579.702319349249;4599.195140850355;4600.700954437951;4488.34918936754;4517.344984977176;4523.140603285382;4594.264917613183;4619.8845028874375;3415.830947348427;3593.6047034826147;4505.526919673436;4506.887327816815;4510.436195475566;4522.23122069249;4537.708457229078;4549.11292298002;4557.384753058328;4572.817087316504;3785.7160712946365;3824.235152969389;3854.3062248795486;3855.652991230803;3883.8834834954996;5270.077736396153;5871.4715675589405;6086.7565297388055;6089.908651370233;6180.783672571483;5361.839144825662;5396.892062158774;5400.540954018913;5402.270693456372;5404.864878378033;6566.901496477557;6675.531383095711;6689.565178263446;6747.493937954673;6755.2231493378395;3674.779513229215;3688.3729765939856;4350.71720338177;4371.519132895379;4383.364412160251;6153.476954458876;6384.882645537059;6396.552241343062;6567.737290508374;7126.893096603555;3717.925641487155;4410.117976404667;4426.756528390905;4429.977850110793;4440.800163992628;4504.436945381827;4508.571685689854;4512.894340886503;4520.773961301988;4526.615237704832;4580.152671755736;4766.856994290177;5267.552258058108;5372.382176049726;5388.54197393226;6161.599496032743;6462.612243265419;6753.425958793459;6788.524803263724;6853.766322062077;1522.72590933633;1537.1545649892514;1539.2150003498296;1542.537238828347;1544.7373946429134;945.4138434170858;997.3317877022413;1002.5187909803335;1007.4156988946394;1011.2567263061644;6319.304876463585;6344.509040375508;6427.939390000691;6454.515911306671;6503.934960650333;6858.417034552019;7089.503734738519;7096.822579435556;7103.715851365991;7116.395106416013;5304.602154838402;6359.20509936258;6377;6396.2964073951825;6407.839804004937;3380.881669141568;3493.654942847022;3631.2192878530714;3663.798491083016;3668.3580373435075;6741.820181468223;6960.338767817049;7270.236625154388;7394;7413.2957369049855;5443.176535860452;6228.719707613168;6637.4026337628875;6725.966370168216;6781.38967492928;7053.929460705533;7138.857782754734;7151;7232.240614735482;7336.853262934711;5143.6399452039495;5737.483626473638;6390.52071094679;7039.54242974205;7142.25006374344;5840;6412.9679351602945;6939.965300173669;6945.930540694529;7066.152061752623;5772.9711351442975;7075.575465472089;7229.060162211502;7400.0379950606075;7410.703571857281;3905.882823515299;4372.956270437382;4431;4461.620762235117;4468.821247150077;5630.5214056805635;6464.482841372773;6868.896966545606;7081.46888983318;7107.862741961261;6662.433693136138;6737.3599508046655;6812.727490900308;6834.897476537955;6892.827679308058;6138.662373569497;6811.978203269549;6844.247132815287;6845.931540684729;6857.594193190484;5310.079456298943;5713.7428815704225;6069.848253793691;6373.705466032317;6688.531802773759;5724.313082430135;5971.940280597139;6848.178218613798;6853.691583878638;6854.314568543105;5798.449147330997;6561.639109849004;6673.399394054364;6679.365460281266;6682.3317668232785 05:04:40 INFO - PID 1639 | |5;yui.html;834.1491678487943;836.7615229659548;838.1283465196196;850.8170743290192;878.3587980774034;16343.18284085797;16742.83257167428;16759.66480670386;18647.627047459042;19305.517362065955;17868.64262714745;18116.4565063048;18272.451826445194;18275.90344579326;18524.351647692332;974.040570038515;974.848898420746;996.2528103922059;999.0908273471143;1002.997002997003;987.0820137272334;999.1008092716577;1000.3447741729179;1001.1690297053428;1002.7292630989629;1034.4982683398546;1060.5704689600725;1061;1061.506399524221;1063.006089306497;1041.416806588313;1045.3832238978962;1049.1187402581868;1050.6112738286884;1060.151878497203;755.6826133024143;764.8470305938835;765.5789315876245;768.9384849212049;769.5190505933791;16851.578710532212;17128;17218.31126754941;17301.30794768208;17329.133543322783;17206.569835754082;17262.136893155417;17443.56391090233;17542.736858947126;17570;12731.490740370375;13684.52104176359;13804.481507034217;14262.57212283633;14278.500252491216;3018.411409775095;3478.808665523395;3483.529723487322;3501;3504.3692135415613;91270;123432;123505.26699963488;123946.38026809899;125350;13002;13031.08782385224;13137.60587182386;13224.272665003517;13241.933790330986;19379.51551211231;24716.13493527754;24993.87527561264;25066;25066.749332506442;7667.386609071261;7821.256980586835;7887.053553573608;7920.970273864361;7946.125926148119;3929.2730844793805;3977.6022397759994;4017.3572228443895;4030.556638769733;4033.576474470197;4168.374743788456;4179.498460184736;4191.706580539394;4220.324748040361;4250.744955302692;923.9907600924039;940.5438362394233;951.538503825644;952;953.5661274120397;634.8287409729135;639.4404895716249;650.4276236911583;659.3867703036127;665.2250128600224;11351.943240283746;11851.762964740828;11909.10681698876;11970.281783093042;12002.459889305002;647.252423450915;655.0665301944709;655.806537071565;669.2838662631035;683.7504310926575;5316.282301889196;5389.110796718497;5449.937262233906;5577.163425486128;5676.744546495335;66138;84406.57796710977;84484.57757711296;86628.56685716657;87880;73954.63022684852;93850.53074734582;94472.52763736274;95834.52082739682;97466;78018.60990695124;78209.60895195487;78302.21697782949;79630.40739185299;79909.60045199736;10828.18788590843;11197.72005699864;11347.829782553434;11371.090312774793;11469.082473402275;44120.55879441164;44722.77638611786;44743.881402965184;44766.77616611896;44824.32763508615;53329;53453.585569731884;54023;54033.45966540284;54945.72527137339;11701.765964680488;13846.446142154202;14040.368183431567;14105.576832695035;14154.575362739135;15491.225438727794;16329.346826127297;17617.119144042492;18509.629807403508;18660.34688785886;15951.202439878192;16112.516624501284;16766.413175538797;16869.24088416049;17140.22868970924;16010.279537421076;16596.751048734037;16789;16996.660066798348;17094.91452542729;16101.597460063593;16413.58966025859;16611.91694041522;16902.661946761244;16945.23746431438;11443.25618834773;11756.94121529387;11896.464659090188;12021.398930053638;12275.447604857982;12802.807957880825;13475.19148851072;13872.653183670489;13879.583612491642;13938 05:04:40 INFO - PID 1639 | __end_tp_report 05:04:40 INFO - PID 1639 | __start_cc_report 05:04:40 INFO - PID 1639 | _x_x_mozilla_cycle_collect,409 05:04:40 INFO - PID 1639 | __end_cc_report 05:04:40 INFO - PID 1639 | __startTimestamp1493813080256__endTimestamp 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | ------- Summary: start ------- 05:04:40 INFO - PID 1639 | Number of tests: 6 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6100.31 Median:6334.94 stddev:791.53 (12.5%) stddev-sans-first:752.63 05:04:40 INFO - PID 1639 | Values: 2882.3 2930.9 3537.9 3571.3 3617.3 5662.2 6122.2 6207.6 6224.3 6228.8 6127.7 6143.6 6155.8 6225.6 6233.0 5250.9 5334.9 5443.7 5484.1 5511.6 4126.1 5589.5 5605.0 5618.5 5633.9 5420.4 5460.2 5500.3 5502.8 5525.1 5488.8 5544.4 5547.8 5564.0 5574.2 5395.6 5441.0 5448.8 5476.8 5505.5 6135.4 6137.6 6201.5 6215.4 6233.9 6435.3 6505.5 6596.4 6634.7 6655.3 6812.6 6813.4 6813.7 6814.3 6828.1 6214.9 6246.0 6288.5 6316.1 6318.5 5503.7 5974.2 6644.7 6674.2 6774.0 5442.8 6316.2 6393.8 6485.4 6604.3 5400.8 6423.9 6631.5 6676.9 6742.9 6058.9 6647.6 6654.4 6665.5 6672.0 6405.4 6484.4 6588.4 6692.9 6699.2 4678.4 4734.7 5294.3 5347.3 5371.4 3728.8 4459.6 4702.0 4726.8 4786.3 5175.6 6162.4 6175.5 6311.8 6335.3 6671.8 6736.3 6749.8 6780.6 6835.4 6294.0 6307.7 6312.7 6314.8 6329.0 5676.1 5978.3 5980.2 5981.6 6000.7 6814.9 6825.9 6879.2 6973.6 7005.1 6761.1 6761.8 6779.8 6811.5 6814.6 6914.4 6964.2 6994.2 6994.4 7025.8 6405.9 6543.0 6562.7 6684.2 6707.6 6667.5 6773.4 6782.8 6794.6 6821.5 6334.9 6406.0 6519.6 6566.0 6586.9 6471.5 6562.7 6571.8 6586.6 6591.7 5388.5 6194.2 6379.9 6440.0 6458.3 6367.3 6368.4 6474.5 6498.8 6534.8 6364.3 6379.7 6380.6 6381.0 6400.0 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:46264.82 Median:35357.82 stddev:81877.00 (231.6%) stddev-sans-first:82035.36 05:04:40 INFO - PID 1639 | Values: 746.1 791.3 820.5 1185.1 1264.7 37355.8 38312.0 38347.4 38352.0 38386.6 38367.6 38570.0 38650.6 38688.0 38722.8 7902.4 8034.0 8310.4 8508.5 8560.7 8367.8 8443.3 8472.4 8476.7 8479.3 8118.8 8411.2 8483.4 8484.4 8500.4 7536.9 8427.8 8446.7 8453.4 8474.5 7532.9 8042.7 8412.5 8417.6 8430.7 37521.8 37740.6 37744.0 37818.4 37878.4 3650.7 3655.1 3690.9 3692.1 3696.0 30772.4 31043.7 31083.5 31155.2 31164.5 3140.5 3174.0 3189.5 3192.3 3203.0 289018.0 289848.0 300065.0 307876.0 308973.0 48183.3 48235.8 48322.5 48338.8 48414.3 50383.2 50461.7 53711.2 54578.0 54721.9 14283.7 14441.1 14441.1 14457.4 14492.0 11448.1 11519.6 11540.3 11558.1 11582.6 13980.7 13984.4 13990.3 13994.9 14053.7 5211.6 6158.8 6175.0 6183.2 6197.6 870.7 917.5 1017.7 1020.9 1058.0 21829.5 24861.3 24983.4 25023.0 25093.9 1125.5 1129.5 1150.5 1163.4 1163.6 12515.2 12680.7 12783.5 12808.4 12811.5 337616.0 341053.9 341525.0 342352.0 342380.0 54442.0 54606.9 54963.0 55028.4 55096.0 1371.0 1502.7 1535.2 1540.3 1556.8 22350.6 24376.5 24398.0 24565.9 24579.5 38252.6 38450.6 38463.4 38491.4 38861.4 292636.0 292798.0 292883.0 293362.0 293811.0 34722.8 35992.8 36380.8 36741.1 36932.1 45037.8 45797.5 46715.5 46931.0 46931.0 30977.2 31009.0 31834.0 31905.0 32304.0 23829.2 24899.9 30849.8 31073.3 32056.8 37468.6 37951.6 37980.2 37981.2 38072.2 23925.9 24930.6 28077.7 31237.4 32167.4 20272.1 23673.8 24867.4 24974.9 24986.6 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:35722.73 Median:12148.40 stddev:94241.01 (775.7%) stddev-sans-first:94468.08 05:04:40 INFO - PID 1639 | Values: 407.2 426.6 431.7 435.2 461.3 16546.0 17085.7 21029.9 21849.8 21859.9 21963.7 22086.2 22241.9 22308.2 22469.0 5951.4 6159.6 6177.3 6195.5 6198.3 5935.1 6041.7 6095.8 6153.5 6159.9 5460.8 5862.5 6092.6 6107.0 6117.1 5852.6 6008.8 6046.5 6089.3 6092.1 5223.9 5364.4 5403.2 5405.7 5410.6 24978.6 26863.0 27000.7 27021.9 27175.9 2225.8 2246.0 2246.5 2271.6 2273.0 14645.2 16908.0 17529.8 17913.2 18047.8 2112.9 2162.1 2207.3 2210.6 2214.0 145141.0 145405.5 145828.0 146737.0 147910.5 11834.6 12335.2 12339.1 12379.4 12383.9 15823.6 16035.6 16070.4 16088.8 16096.7 8727.5 8915.3 8954.2 8966.7 8982.1 8517.8 8530.7 8550.9 8703.7 8704.1 7508.7 7654.6 7675.2 7676.5 7683.7 2109.7 2127.8 2128.8 2130.1 2139.3 949.6 968.9 984.8 1022.1 1035.6 20681.9 21091.4 21187.0 21374.0 25675.9 1002.1 1015.3 1029.0 1032.2 1036.2 10226.7 11043.2 12995.9 13908.0 14018.0 526338.0 545173.0 558520.0 570633.0 578565.0 137509.0 138498.0 138886.0 139908.6 140361.3 1187.1 1214.4 1241.7 1243.4 1246.4 16857.2 20626.8 20679.0 21132.0 21720.0 44286.3 48688.8 52188.5 54301.0 60044.4 79368.6 79392.0 80623.8 81218.6 81656.0 7337.3 8794.1 8878.5 8938.0 9041.6 14737.9 14744.9 14747.5 15134.7 15241.2 10432.8 12005.3 12024.7 12155.8 12192.1 10871.9 11546.9 11617.3 11786.5 12032.0 10843.2 11808.4 12220.9 12221.5 12545.1 8015.2 8757.5 8769.9 8869.5 9225.5 10365.0 11930.3 11965.8 12196.5 12251.4 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7155.01 Median:4142.99 stddev:4526.73 (109.3%) stddev-sans-first:4517.45 05:04:40 INFO - PID 1639 | Values: 1220.0 1423.3 1442.5 1456.9 1462.3 11312.2 11755.9 11820.6 11853.8 12018.8 11758.7 11928.6 11949.1 11949.3 11950.5 2518.9 2551.6 2561.8 2568.8 2571.4 2476.1 2524.0 2525.8 2539.0 2547.2 2503.6 2510.1 2543.2 2550.8 2555.6 2466.4 2482.3 2509.1 2510.8 2515.8 2419.9 2502.2 2502.6 2513.3 2516.8 11728.8 11804.9 11894.5 11904.6 11927.3 6505.7 6533.4 6608.1 6858.6 6879.2 4267.6 4346.9 4468.4 4487.7 4556.8 1258.9 1269.7 1288.3 1317.6 1318.3 15707.5 16192.6 16490.0 16625.6 16849.1 5336.5 5351.7 5560.6 5658.4 5751.3 10874.4 10985.9 11795.4 11910.1 11923.8 6531.0 6548.1 6567.7 6592.5 6607.3 5951.8 6090.6 6244.4 6246.5 6252.9 4277.4 4394.8 4417.0 4445.9 4458.7 1255.9 1277.9 1279.5 1292.3 1299.1 736.6 777.1 789.5 815.7 821.5 6688.6 6993.3 7031.6 7034.2 7109.6 847.9 906.4 913.3 914.6 915.4 4041.3 4244.6 4368.9 4455.0 4462.1 14793.9 15159.4 15269.2 15298.8 15326.8 14605.6 15086.8 15158.5 15172.1 15201.5 6420.3 8577.4 8744.2 8751.0 8768.1 5962.5 6231.2 7045.4 7083.4 7138.0 13294.5 13374.5 13531.1 13809.2 13997.0 13682.9 13834.5 14019.8 14182.4 14243.1 7330.5 8597.7 8654.2 8668.5 8672.6 10935.3 11026.4 11027.9 11030.2 11041.4 8950.1 9280.8 9333.0 9339.7 9372.6 9115.5 9121.0 9162.3 9185.7 9214.7 8211.6 8373.1 8381.1 8386.4 8400.2 5385.4 5769.9 5931.8 6086.0 6215.3 5765.0 6074.3 6094.3 6200.1 6378.3 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5360.00 Median:6088.33 stddev:1734.21 (28.5%) stddev-sans-first:1697.99 05:04:40 INFO - PID 1639 | Values: 360.3 388.3 410.7 413.7 416.9 5351.9 6296.3 6296.9 6439.4 6517.3 4784.6 6158.6 6175.4 6278.4 6285.8 4524.2 4571.4 4579.7 4599.2 4600.7 4488.3 4517.3 4523.1 4594.3 4619.9 3415.8 3593.6 4505.5 4506.9 4510.4 4522.2 4537.7 4549.1 4557.4 4572.8 3785.7 3824.2 3854.3 3855.7 3883.9 5270.1 5871.5 6086.8 6089.9 6180.8 5361.8 5396.9 5400.5 5402.3 5404.9 6566.9 6675.5 6689.6 6747.5 6755.2 3674.8 3688.4 4350.7 4371.5 4383.4 6153.5 6384.9 6396.6 6567.7 7126.9 3717.9 4410.1 4426.8 4430.0 4440.8 4504.4 4508.6 4512.9 4520.8 4526.6 4580.2 4766.9 5267.6 5372.4 5388.5 6161.6 6462.6 6753.4 6788.5 6853.8 1522.7 1537.2 1539.2 1542.5 1544.7 945.4 997.3 1002.5 1007.4 1011.3 6319.3 6344.5 6427.9 6454.5 6503.9 6858.4 7089.5 7096.8 7103.7 7116.4 5304.6 6359.2 6377.0 6396.3 6407.8 3380.9 3493.7 3631.2 3663.8 3668.4 6741.8 6960.3 7270.2 7394.0 7413.3 5443.2 6228.7 6637.4 6726.0 6781.4 7053.9 7138.9 7151.0 7232.2 7336.9 5143.6 5737.5 6390.5 7039.5 7142.3 5840.0 6413.0 6940.0 6945.9 7066.2 5773.0 7075.6 7229.1 7400.0 7410.7 3905.9 4373.0 4431.0 4461.6 4468.8 5630.5 6464.5 6868.9 7081.5 7107.9 6662.4 6737.4 6812.7 6834.9 6892.8 6138.7 6812.0 6844.2 6845.9 6857.6 5310.1 5713.7 6069.8 6373.7 6688.5 5724.3 5971.9 6848.2 6853.7 6854.3 5798.4 6561.6 6673.4 6679.4 6682.3 05:04:40 INFO - PID 1639 | 05:04:40 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:20865.84 Median:18653.99 stddev:28362.23 (152.0%) stddev-sans-first:28401.91 05:04:40 INFO - PID 1639 | Values: 834.1 836.8 838.1 850.8 878.4 16343.2 16742.8 16759.7 18647.6 19305.5 17868.6 18116.5 18272.5 18275.9 18524.4 974.0 974.8 996.3 999.1 1003.0 987.1 999.1 1000.3 1001.2 1002.7 1034.5 1060.6 1061.0 1061.5 1063.0 1041.4 1045.4 1049.1 1050.6 1060.2 755.7 764.8 765.6 768.9 769.5 16851.6 17128.0 17218.3 17301.3 17329.1 17206.6 17262.1 17443.6 17542.7 17570.0 12731.5 13684.5 13804.5 14262.6 14278.5 3018.4 3478.8 3483.5 3501.0 3504.4 91270.0 123432.0 123505.3 123946.4 125350.0 13002.0 13031.1 13137.6 13224.3 13241.9 19379.5 24716.1 24993.9 25066.0 25066.7 7667.4 7821.3 7887.1 7921.0 7946.1 3929.3 3977.6 4017.4 4030.6 4033.6 4168.4 4179.5 4191.7 4220.3 4250.7 924.0 940.5 951.5 952.0 953.6 634.8 639.4 650.4 659.4 665.2 11351.9 11851.8 11909.1 11970.3 12002.5 647.3 655.1 655.8 669.3 683.8 5316.3 5389.1 5449.9 5577.2 5676.7 66138.0 84406.6 84484.6 86628.6 87880.0 73954.6 93850.5 94472.5 95834.5 97466.0 78018.6 78209.6 78302.2 79630.4 79909.6 10828.2 11197.7 11347.8 11371.1 11469.1 44120.6 44722.8 44743.9 44766.8 44824.3 53329.0 53453.6 54023.0 54033.5 54945.7 11701.8 13846.4 14040.4 14105.6 14154.6 15491.2 16329.3 17617.1 18509.6 18660.3 15951.2 16112.5 16766.4 16869.2 17140.2 16010.3 16596.8 16789.0 16996.7 17094.9 16101.6 16413.6 16611.9 16902.7 16945.2 11443.3 11756.9 11896.5 12021.4 12275.4 12802.8 13475.2 13872.7 13879.6 13938.0 05:04:40 INFO - PID 1639 | -------- Summary: end -------- 05:04:40 INFO - PID 1639 | 05:04:40 INFO - TEST-INFO | 1639: exit 0 05:04:41 INFO - TEST-OK | dromaeo_css | took 1114732ms 05:04:41 INFO - TEST-START | kraken 05:04:41 INFO - operating with platform_type : mac_ 05:04:41 INFO - Initialising browser for kraken test... 05:04:41 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpk9tB7w/profile http://localhost:49240/getInfo.html) 05:04:43 INFO - PID 1652 | 2017-05-03 05:04:43.892 plugin-container[1653:10089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 05:04:43 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:43 INFO - PID 1652 | 2017-05-03 05:04:43.892 plugin-container[1653:10089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:04:43 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:43 INFO - PID 1652 | 2017-05-03 05:04:43.892 plugin-container[1653:10089] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:43 INFO - PID 1652 | __metrics Screen width/height:1600/1200 05:04:43 INFO - PID 1652 | colorDepth:24 05:04:43 INFO - PID 1652 | Browser inner width/height: 1024/691 05:04:43 INFO - PID 1652 | __metrics 05:04:44 INFO - TEST-INFO | 1652: exit 0 05:04:44 INFO - Browser initialized. 05:04:44 INFO - Running cycle 1/1 for kraken test... 05:04:44 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpk9tB7w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:47 INFO - PID 1654 | 2017-05-03 05:04:47.290 plugin-container[1655:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:04:47 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:47 INFO - PID 1654 | 2017-05-03 05:04:47.291 plugin-container[1655:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:04:47 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:47 INFO - PID 1654 | 2017-05-03 05:04:47.291 plugin-container[1655:10216] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:57 INFO - PID 1654 | RSS: Main: 234602496 05:04:57 INFO - PID 1654 | 05:05:00 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:01 INFO - PID 1654 | RSS: Main: 247906304 05:05:01 INFO - PID 1654 | 05:05:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 05:05:04 INFO - PID 1654 | RSS: Main: 256331776 05:05:04 INFO - PID 1654 | 05:05:07 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 05:05:08 INFO - PID 1654 | RSS: Main: 258801664 05:05:08 INFO - PID 1654 | 05:05:10 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 05:05:10 INFO - PID 1654 | RSS: Main: 260997120 05:05:10 INFO - PID 1654 | 05:05:12 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:13 INFO - PID 1654 | RSS: Main: 261410816 05:05:13 INFO - PID 1654 | 05:05:16 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:17 INFO - PID 1654 | RSS: Main: 267304960 05:05:17 INFO - PID 1654 | 05:05:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:20 INFO - PID 1654 | RSS: Main: 268607488 05:05:20 INFO - PID 1654 | 05:05:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 05:05:24 INFO - PID 1654 | RSS: Main: 268259328 05:05:24 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:26 INFO - PID 1654 | RSS: Main: 267669504 05:05:26 INFO - PID 1654 | 05:05:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:28 INFO - PID 1654 | RSS: Main: 267874304 05:05:28 INFO - PID 1654 | 05:05:30 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:31 INFO - PID 1654 | RSS: Main: 267329536 05:05:31 INFO - PID 1654 | 05:05:34 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:35 INFO - PID 1654 | RSS: Main: 267358208 05:05:35 INFO - PID 1654 | 05:05:38 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:38 INFO - PID 1654 | RSS: Main: 267894784 05:05:38 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 05:05:40 INFO - PID 1654 | RSS: Main: 269385728 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | __start_tp_report 05:05:40 INFO - PID 1654 | _x_x_mozilla_page_load 05:05:40 INFO - PID 1654 | _x_x_mozilla_page_load_details 05:05:40 INFO - PID 1654 | |i|pagename|runs| 05:05:40 INFO - PID 1654 | |0;ai-astar;103;124;170;99;143;104;148;114;159;169 05:05:40 INFO - PID 1654 | |1;audio-beat-detection;154;111;111;148;112;112;114;117;129;149 05:05:40 INFO - PID 1654 | |2;audio-dft;197;205;202;270;256;267;281;208;197;199 05:05:40 INFO - PID 1654 | |3;audio-fft;82;73;90;110;86;62;62;63;76;48 05:05:40 INFO - PID 1654 | |4;audio-oscillator;82;71;81;71;73;71;86;72;87;78 05:05:40 INFO - PID 1654 | |5;imaging-gaussian-blur;109;89;89;94;90;99;89;89;78;134 05:05:40 INFO - PID 1654 | |6;imaging-darkroom;98;98;98;98;98;97;98;99;96;96 05:05:40 INFO - PID 1654 | |7;imaging-desaturate;91;92;91;93;93;90;92;92;92;90 05:05:40 INFO - PID 1654 | |8;json-parse-financial;55;52;51;50;52;52;51;52;58;58 05:05:40 INFO - PID 1654 | |9;json-stringify-tinderbox;58;56;67;56;59;59;56;57;55;55 05:05:40 INFO - PID 1654 | |10;stanford-crypto-aes;81;82;83;83;85;82;84;84;82;85 05:05:40 INFO - PID 1654 | |11;stanford-crypto-ccm;133;159;154;139;146;156;162;142;186;166 05:05:40 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;187;182;186;190;185;182;209;235;187;184 05:05:40 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;70;69;67;66;84;100;78;70;68;69 05:05:40 INFO - PID 1654 | __end_tp_report 05:05:40 INFO - PID 1654 | __start_cc_report 05:05:40 INFO - PID 1654 | _x_x_mozilla_cycle_collect,977 05:05:40 INFO - PID 1654 | __end_cc_report 05:05:40 INFO - PID 1654 | __startTimestamp1493813140846__endTimestamp 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | ------- Summary: start ------- 05:05:40 INFO - PID 1654 | Number of tests: 14 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:133.30 Median:153.50 stddev:27.90 (18.2%) stddev-sans-first:27.35 05:05:40 INFO - PID 1654 | Values: 103.0 124.0 170.0 99.0 143.0 104.0 148.0 114.0 159.0 169.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:123.00 stddev:17.86 (14.5%) stddev-sans-first:15.74 05:05:40 INFO - PID 1654 | Values: 154.0 111.0 111.0 148.0 112.0 112.0 114.0 117.0 129.0 149.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:228.20 Median:232.00 stddev:35.35 (15.2%) stddev-sans-first:35.64 05:05:40 INFO - PID 1654 | Values: 197.0 205.0 202.0 270.0 256.0 267.0 281.0 208.0 197.0 199.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:75.20 Median:74.50 stddev:17.75 (23.8%) stddev-sans-first:18.66 05:05:40 INFO - PID 1654 | Values: 82.0 73.0 90.0 110.0 86.0 62.0 62.0 63.0 76.0 48.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:79.50 stddev:6.43 (8.1%) stddev-sans-first:6.58 05:05:40 INFO - PID 1654 | Values: 82.0 71.0 81.0 71.0 73.0 71.0 86.0 72.0 87.0 78.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:89.00 stddev:15.57 (17.5%) stddev-sans-first:15.79 05:05:40 INFO - PID 1654 | Values: 109.0 89.0 89.0 94.0 90.0 99.0 89.0 89.0 78.0 134.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:98.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 05:05:40 INFO - PID 1654 | Values: 98.0 98.0 98.0 98.0 98.0 97.0 98.0 99.0 96.0 96.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:92.00 stddev:1.07 (1.2%) stddev-sans-first:1.12 05:05:40 INFO - PID 1654 | Values: 91.0 92.0 91.0 93.0 93.0 90.0 92.0 92.0 92.0 90.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:52.00 stddev:2.88 (5.5%) stddev-sans-first:2.98 05:05:40 INFO - PID 1654 | Values: 55.0 52.0 51.0 50.0 52.0 52.0 51.0 52.0 58.0 58.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:57.50 stddev:3.55 (6.2%) stddev-sans-first:3.77 05:05:40 INFO - PID 1654 | Values: 58.0 56.0 67.0 56.0 59.0 59.0 56.0 57.0 55.0 55.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:1.37 (1.6%) stddev-sans-first:1.22 05:05:40 INFO - PID 1654 | Values: 81.0 82.0 83.0 83.0 85.0 82.0 84.0 84.0 82.0 85.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:157.50 stddev:15.40 (9.8%) stddev-sans-first:14.27 05:05:40 INFO - PID 1654 | Values: 133.0 159.0 154.0 139.0 146.0 156.0 162.0 142.0 186.0 166.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.70 Median:187.00 stddev:16.79 (9.0%) stddev-sans-first:17.68 05:05:40 INFO - PID 1654 | Values: 187.0 182.0 186.0 190.0 185.0 182.0 209.0 235.0 187.0 184.0 05:05:40 INFO - PID 1654 | 05:05:40 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:69.50 stddev:10.66 (15.3%) stddev-sans-first:11.20 05:05:40 INFO - PID 1654 | Values: 70.0 69.0 67.0 66.0 84.0 100.0 78.0 70.0 68.0 69.0 05:05:40 INFO - PID 1654 | -------- Summary: end -------- 05:05:40 INFO - PID 1654 | 05:05:41 INFO - TEST-INFO | 1654: exit 0 05:05:41 INFO - TEST-OK | kraken | took 60896ms 05:05:41 INFO - SUITE-END | took 1175s 05:05:42 INFO - Completed test suite (00:19:35) 05:05:42 INFO - javascript benchmark 05:05:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7892.678347473172, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2882.279430142464, 2930.886263219976, 3537.893863184104, 3571.2678900825335, 3617.3307938031467, 5662.207290979261, 6122.204113465244, 6207.627542347463, 6224.284207316155, 6228.781992630254, 6127.739336776409, 6143.573288551031, 6155.846103847406, 6225.564210505267, 6232.968835155818, 5250.923560670053, 5334.946650533503, 5443.700596467193, 5484.122540393528, 5511.614187006911, 4126.112885729567, 5589.524890384322, 5604.971975140119, 5618.4662457066515, 5633.943660563404, 5420.403755586883, 5460.180972854064, 5500.312460942382, 5502.80740174092, 5525.11598144299, 5488.7530061147345, 5544.445555444464, 5547.750351234205, 5563.998480273549, 5574.219609254707, 5395.649282796606, 5441.047816632113, 5448.754806033738, 5476.808311709111, 5505.476979686923, 6135.447809697104, 6137.631742095488, 6201.503879689614, 6215.409536094064, 6233.937660623426, 6435.259945106326, 6505.544611877171, 6596.373344532261, 6634.734610615618, 6655.301193374675, 6812.557183783038, 6813.386795188408, 6813.659317034128, 6814.2845001274645, 6828.078209441712, 6214.937850621526, 6245.969415046512, 6288.496920246369, 6316.052592111174, 6318.526110541728, 5503.697296648643, 5974.223350964434, 6644.734210631521, 6674.199096108498, 6774.0, 5442.782288708487, 6316.242050953915, 6393.7762178324465, 6485.4163125318, 6604.3395660434535, 5400.837974860682, 6423.907935650951, 6631.469482441393, 6676.899846502306, 6742.932570674328, 6058.878822423527, 6647.634380109141, 6654.367835055662, 6665.533412661067, 6671.966640166768, 6405.391487808651, 6484.416402523795, 6588.374104460068, 6692.866142677217, 6699.162604674416, 4678.391809064799, 4734.668573199843, 5294.285271488377, 5347.304850369427, 5371.409144994048, 3728.794916279631, 4459.576340247671, 4702.012577358724, 4726.810927562928, 4786.2820576913045, 5175.585953123817, 6162.352952939967, 6175.536834737412, 6311.779087731908, 6335.334789847, 6671.833204169838, 6736.292689267654, 6749.831254218586, 6780.627065511346, 6835.350641689031, 6294.0, 6307.684615769285, 6312.684365781784, 6314.810555683337, 6328.968355158195, 5676.120201368794, 5978.342382337939, 5980.192673989043, 5981.581289309706, 6000.669963151981, 6814.863702725917, 6825.897611535732, 6879.174499060145, 6973.58158510491, 7005.0893383859775, 6761.053452516553, 6761.797146085625, 6779.762708305186, 6811.489138314547, 6814.625195614289, 6914.377706006484, 6964.199117101598, 6994.195667498099, 6994.370506654425, 7025.824354391181, 6405.903911441239, 6542.967285163544, 6562.737490500326, 6684.197896252481, 6707.5975441473665, 6667.49993750461, 6773.390394864486, 6782.796516104525, 6794.626295553792, 6821.488388370992, 6334.9366506334345, 6406.0, 6519.576227545194, 6565.967170164118, 6586.934130658632, 6471.546991710535, 6562.737490500517, 6571.769988050395, 6586.571872828251, 6591.703373348307, 5388.461153884737, 6194.163787888578, 6379.872402552016, 6440.0, 6458.321876203119, 6367.331430199948, 6368.395002474757, 6474.4820414365795, 6498.772542960974, 6534.803955881332, 6364.331745166876, 6379.6810159490915, 6380.649064301508, 6381.042843573501, 6400.0], "value": 6043.741350779139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.0934964018716, 791.2917938445091, 820.470796336363, 1185.063799598318, 1264.7344057747873, 37355.813220933895, 38312.04219894497, 38347.424788628196, 38352.0, 38386.61613383865, 38367.61632383675, 38570.0, 38650.61349386505, 38688.0, 38722.83831485053, 7902.446828721992, 8033.95983020087, 8310.376721745894, 8508.489490630582, 8560.700375486858, 8367.790805229888, 8443.324534037292, 8472.406931514797, 8476.745697629081, 8479.321654267644, 8118.837623247531, 8411.15888411157, 8483.406161568693, 8484.406091573592, 8500.404971651988, 7536.924630753677, 8427.789305267355, 8446.662133514652, 8453.408261421702, 8474.491530508127, 7532.887006694904, 8042.718504852302, 8412.495250285003, 8417.621207045697, 8430.747077587683, 37521.81239093815, 37740.62259377399, 37744.0, 37818.43272350917, 37878.43182352267, 3650.653187947141, 3655.067957670777, 3690.9261814763554, 3692.058525076115, 3695.9630403696156, 30772.384552309053, 31043.689563104304, 31083.533746993813, 31155.221119471968, 31164.53253201204, 3140.5289206618963, 3173.984130079358, 3189.5375170600364, 3192.3296107817387, 3203.0390882735087, 289018.0, 289848.0, 300065.0, 307875.9999999978, 308973.0, 48183.27725084127, 48235.75882120567, 48322.516774832504, 48338.75830620895, 48414.27378589324, 50383.24425133626, 50461.74769126204, 53711.19433208505, 54578.0, 54721.90556188933, 14283.714325713427, 14441.061331013661, 14441.133531988115, 14457.421703131757, 14492.0, 11448.141389395829, 11519.596814111634, 11540.307581545134, 11558.075353971662, 11582.594609188638, 13980.72038559223, 13984.440622375194, 13990.30048497571, 13994.88040956721, 14053.718925621635, 5211.635185536976, 6158.753649854045, 6175.0, 6183.165272688129, 6197.628142311476, 870.6822009966317, 917.5320586500933, 1017.7150397888603, 1020.938224246775, 1057.9735506612396, 21829.454263643536, 24861.254162375157, 24983.37541561439, 25022.99908003696, 25093.87453062723, 1125.5272785430138, 1129.4635048352131, 1150.465033759306, 1163.3892206591606, 1163.627639155479, 12515.249085054744, 12680.74638507243, 12783.488660453664, 12808.423620937081, 12811.476843868011, 337616.0, 341053.88419173734, 341525.0, 342352.0, 342380.0, 54442.0, 54606.904482164, 54963.0, 55028.449715503135, 55096.0, 1370.9725805484034, 1502.7370210213169, 1535.1556643846063, 1540.3453532248973, 1556.820965588949, 22350.552988940457, 24376.51246975086, 24398.0, 24565.877170614032, 24579.508409832062, 38252.617473826016, 38450.61549384582, 38463.423048654855, 38491.42262866115, 38861.417078743274, 292636.0, 292798.0, 292883.0, 293362.0, 293811.0, 34722.826385867906, 35992.820035899655, 36380.81809590935, 36741.08147296339, 36932.07669808276, 45037.77481112573, 45797.54202458066, 46715.532844672474, 46931.0, 46931.0, 30977.225569360948, 31009.0, 31834.044978650676, 31905.042848714576, 32304.0, 23829.165979190646, 24899.87550062238, 30849.845750772, 31073.26972407884, 32056.83971580127, 37468.6253137476, 37951.620483795916, 37980.24039519139, 37981.24037519179, 38072.238555228185, 23925.880370598035, 24930.626040609768, 28077.71922280751, 31237.375252495276, 32167.356652866343, 20272.087756050718, 23673.816309184127, 24867.378315542257, 24974.875125624254, 24986.62520062237], "value": 17688.534803262424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.2425288962529, 426.5947350017484, 431.6827132057937, 435.18185810675925, 461.30573486902205, 16546.0, 17085.743713844273, 21029.948502574887, 21849.781502185015, 21859.890700546493, 21963.6705449418, 22086.226982055632, 22241.888790556026, 22308.21921232757, 22469.0, 5951.375105613914, 6159.630422174684, 6177.320494745574, 6195.535334849882, 6198.256209254896, 5935.080062590306, 6041.667708276043, 6095.7561697532265, 6153.538484613672, 6159.907601385983, 5460.808871689492, 5862.534366408398, 6092.573519853612, 6107.0, 6117.143599895996, 5852.561057920651, 6008.789692360769, 6046.546509011841, 6089.299730531001, 6092.086187071908, 5223.921641175385, 5364.383095943977, 5403.162509810985, 5405.675659460445, 5410.621256512046, 24978.625320620205, 26863.0, 27000.729992700213, 27021.864890675617, 27175.86412067927, 2225.810806081485, 2245.9663105053437, 2246.472079061419, 2271.5911135995516, 2272.9772702273094, 14645.19451430171, 16907.985520868788, 17529.824701752947, 17913.19390627425, 18047.819521804875, 2112.8732276063483, 2162.0811155258953, 2207.3377986603955, 2210.5689390568846, 2213.966790498144, 145141.0, 145405.5475872616, 145828.0, 146737.0, 147910.52089479074, 11834.644960651194, 12335.211394347769, 12339.13626046186, 12379.442925068393, 12383.938080309541, 15823.604409889846, 16035.599110022109, 16070.43753468623, 16088.75866861998, 16096.67806643884, 8727.476351418934, 8915.28677705782, 8954.246420385127, 8966.68616598416, 8982.101789821096, 8517.829643407098, 8530.70142545008, 8550.871736923951, 8703.6518539259, 8704.086070962585, 7508.699652013967, 7654.617269136521, 7675.194104618935, 7676.462647614725, 7683.731069412655, 2109.6624540073512, 2127.8084972352563, 2128.7764784697692, 2130.1053557505884, 2139.28334008109, 949.5501605856873, 968.941863488193, 984.7735020945223, 1022.1209759606733, 1035.6478797208986, 20681.89659051695, 21091.367258982256, 21187.0, 21374.0, 25675.871620641778, 1002.1231422505308, 1015.2791518022287, 1028.9742756431149, 1032.230987914009, 1036.160709825044, 10226.693199204035, 11043.171762117874, 12995.935020324838, 13908.0, 14018.0, 526338.0, 545173.0, 558520.0, 570633.0, 578565.0, 137509.0, 138498.0, 138886.0, 139908.6009139916, 140361.29819350838, 1187.0859438232512, 1214.3988725580718, 1241.7019915220462, 1243.384524660299, 1246.4141853328922, 16857.21855893764, 20626.793732062488, 20679.0, 21132.0, 21720.0, 44286.33570496381, 48688.782780430774, 52188.478115218364, 54301.0, 60044.39955600388, 79368.60315698385, 79392.0, 80623.79064314158, 81218.59390703008, 81656.0, 7337.26627337262, 8794.120587941155, 8878.5116818573, 8938.0, 9041.638334466548, 14737.852621473648, 14744.926275368554, 14747.483838065617, 15134.697306053597, 15241.237938103272, 10432.843507347548, 12005.279683219034, 12024.699382515508, 12155.756884862076, 12192.146549741434, 10871.891281087088, 11546.94226528862, 11617.302961822317, 11786.528538858349, 12032.0, 10843.186760993114, 11808.350540720343, 12220.938895305466, 12221.450034748279, 12545.121841471375, 8015.236647937444, 8757.47455152693, 8769.930524316438, 8869.512176830078, 9225.538723063686, 10365.0, 11930.284182949052, 11965.820512692491, 12196.451159698012, 12251.448684809027], "value": 10463.408976359706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.9512019519218, 1423.3452611798573, 1442.4807069455, 1456.8543145685433, 1462.341946124244, 11312.208145429824, 11755.941220293907, 11820.645380638573, 11853.822192667107, 12018.759624807499, 11758.706032349195, 11928.64214073577, 11949.103817213721, 11949.283043017425, 11950.462229199686, 2518.8866501007406, 2551.566233740259, 2561.7566331198504, 2568.7816535594498, 2571.3828681116493, 2476.1333533263396, 2524.0408644715076, 2525.785308248801, 2538.9746102539016, 2547.1594373856674, 2503.5994240921455, 2510.083819405913, 2543.2370288913344, 2550.7831834294107, 2555.6038813983864, 2466.3587467258553, 2482.329770961839, 2509.0841842727455, 2510.7614776596192, 2515.8490490570534, 2419.879006049708, 2502.199296225209, 2502.587073132931, 2513.283714141461, 2516.8112391570708, 11728.765424691544, 11804.881951180465, 11894.464749086312, 11904.58333958316, 11927.284362938164, 6505.674716264215, 6533.431976325695, 6608.107905432753, 6858.588484690935, 6879.208890977551, 4267.579517900436, 4346.869593912188, 4468.436047383422, 4487.685861989662, 4556.840510582147, 1258.8867001969866, 1269.6668498076983, 1288.3493835613049, 1317.55203230901, 1318.294712328909, 15707.528774136794, 16192.595185120348, 16490.010599363955, 16625.584360390967, 16849.07330096844, 5336.519713225828, 5351.705656188947, 5560.582956278296, 5658.405867383948, 5751.309842818805, 10874.401907894984, 10985.945070274758, 11795.35125568085, 11910.106741994385, 11923.761524769456, 6530.967345163243, 6548.116004339448, 6567.704453299613, 6592.472602191813, 6607.339266073451, 5951.791687291011, 6090.573659843857, 6244.438000579969, 6246.46905013079, 6252.906206406908, 4277.358396240526, 4394.835368627317, 4416.955830441654, 4445.911081778347, 4458.71018383804, 1255.8869701726887, 1277.9424925878359, 1279.456231101778, 1292.3150730113055, 1299.0841456773142, 736.5617457612711, 777.1412589088975, 789.5302295134386, 815.6941147069849, 821.4742564758559, 6688.598684078971, 6993.2657071007825, 7031.578105313698, 7034.155901291878, 7109.608971506616, 847.855864503036, 906.3564868943124, 913.3423934766957, 914.6387177065022, 915.4187091197005, 4041.333180025322, 4244.639205667491, 4368.9126217476105, 4455.0, 4462.129884672458, 14793.852061479247, 15159.393624255126, 15269.236538173047, 15298.847011529742, 15326.846731532542, 14605.634859128395, 15086.849131508545, 15158.469453569072, 15172.089674619558, 15201.467948621745, 6420.325865784119, 8577.4424662398, 8744.169303916118, 8751.0, 8768.123187681182, 5962.4633782959745, 6231.158793562811, 7045.365917067488, 7083.362497375261, 7138.0, 13294.468221271041, 13374.465021399035, 13531.120477168952, 13809.171449713049, 13997.0, 13682.931585342409, 13834.515791947233, 14019.789703154256, 14182.361793719514, 14243.145411275356, 7330.5235159716585, 8597.742067737978, 8654.22112009922, 8668.479891206547, 8672.566371681516, 10935.28920620157, 11026.44867756593, 11027.944860275647, 11030.172737044593, 11041.44792760375, 8950.14973577509, 9280.767980800263, 9333.020032896447, 9339.673111441067, 9372.625094996123, 9115.498647574183, 9120.954395227982, 9162.312826537904, 9185.724428267162, 9214.677486287948, 8211.58942052907, 8373.120822313569, 8381.11998240176, 8386.412951093609, 8400.24397804176, 5385.38068122171, 5769.91345129815, 5931.792387266598, 6085.9695701521205, 6215.285242197205, 5765.0, 6074.331823499323, 6094.329623741473, 6200.0779758275075, 6378.298387177314], "value": 5415.015234594646, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.3225935241745, 388.32237745134745, 410.730971213855, 413.7248729594818, 416.8728537795973, 5351.879053129088, 6296.338884417139, 6296.937030629692, 6439.388258115483, 6517.315681853408, 4784.5693887550115, 6158.599691020092, 6175.413335733109, 6278.403551662596, 6285.779997700081, 4524.163029839474, 4571.3828633134435, 4579.702319349249, 4599.195140850355, 4600.700954437951, 4488.34918936754, 4517.344984977176, 4523.140603285382, 4594.264917613183, 4619.8845028874375, 3415.830947348427, 3593.6047034826147, 4505.526919673436, 4506.887327816815, 4510.436195475566, 4522.23122069249, 4537.708457229078, 4549.11292298002, 4557.384753058328, 4572.817087316504, 3785.7160712946365, 3824.235152969389, 3854.3062248795486, 3855.652991230803, 3883.8834834954996, 5270.077736396153, 5871.4715675589405, 6086.7565297388055, 6089.908651370233, 6180.783672571483, 5361.839144825662, 5396.892062158774, 5400.540954018913, 5402.270693456372, 5404.864878378033, 6566.901496477557, 6675.531383095711, 6689.565178263446, 6747.493937954673, 6755.2231493378395, 3674.779513229215, 3688.3729765939856, 4350.71720338177, 4371.519132895379, 4383.364412160251, 6153.476954458876, 6384.882645537059, 6396.552241343062, 6567.737290508374, 7126.893096603555, 3717.925641487155, 4410.117976404667, 4426.756528390905, 4429.977850110793, 4440.800163992628, 4504.436945381827, 4508.571685689854, 4512.894340886503, 4520.773961301988, 4526.615237704832, 4580.152671755736, 4766.856994290177, 5267.552258058108, 5372.382176049726, 5388.54197393226, 6161.599496032743, 6462.612243265419, 6753.425958793459, 6788.524803263724, 6853.766322062077, 1522.72590933633, 1537.1545649892514, 1539.2150003498296, 1542.537238828347, 1544.7373946429134, 945.4138434170858, 997.3317877022413, 1002.5187909803335, 1007.4156988946394, 1011.2567263061644, 6319.304876463585, 6344.509040375508, 6427.939390000691, 6454.515911306671, 6503.934960650333, 6858.417034552019, 7089.503734738519, 7096.822579435556, 7103.715851365991, 7116.395106416013, 5304.602154838402, 6359.20509936258, 6377.0, 6396.2964073951825, 6407.839804004937, 3380.881669141568, 3493.654942847022, 3631.2192878530714, 3663.798491083016, 3668.3580373435075, 6741.820181468223, 6960.338767817049, 7270.236625154388, 7394.0, 7413.2957369049855, 5443.176535860452, 6228.719707613168, 6637.4026337628875, 6725.966370168216, 6781.38967492928, 7053.929460705533, 7138.857782754734, 7151.0, 7232.240614735482, 7336.853262934711, 5143.6399452039495, 5737.483626473638, 6390.52071094679, 7039.54242974205, 7142.25006374344, 5840.0, 6412.9679351602945, 6939.965300173669, 6945.930540694529, 7066.152061752623, 5772.9711351442975, 7075.575465472089, 7229.060162211502, 7400.0379950606075, 7410.703571857281, 3905.882823515299, 4372.956270437382, 4431.0, 4461.620762235117, 4468.821247150077, 5630.5214056805635, 6464.482841372773, 6868.896966545606, 7081.46888983318, 7107.862741961261, 6662.433693136138, 6737.3599508046655, 6812.727490900308, 6834.897476537955, 6892.827679308058, 6138.662373569497, 6811.978203269549, 6844.247132815287, 6845.931540684729, 6857.594193190484, 5310.079456298943, 5713.7428815704225, 6069.848253793691, 6373.705466032317, 6688.531802773759, 5724.313082430135, 5971.940280597139, 6848.178218613798, 6853.691583878638, 6854.314568543105, 5798.449147330997, 6561.639109849004, 6673.399394054364, 6679.365460281266, 6682.3317668232785], "value": 4818.221606400217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.1491678487943, 836.7615229659548, 838.1283465196196, 850.8170743290192, 878.3587980774034, 16343.18284085797, 16742.83257167428, 16759.66480670386, 18647.627047459042, 19305.517362065955, 17868.64262714745, 18116.4565063048, 18272.451826445194, 18275.90344579326, 18524.351647692332, 974.040570038515, 974.848898420746, 996.2528103922059, 999.0908273471143, 1002.997002997003, 987.0820137272334, 999.1008092716577, 1000.3447741729179, 1001.1690297053428, 1002.7292630989629, 1034.4982683398546, 1060.5704689600725, 1061.0, 1061.506399524221, 1063.006089306497, 1041.416806588313, 1045.3832238978962, 1049.1187402581868, 1050.6112738286884, 1060.151878497203, 755.6826133024143, 764.8470305938835, 765.5789315876245, 768.9384849212049, 769.5190505933791, 16851.578710532212, 17128.0, 17218.31126754941, 17301.30794768208, 17329.133543322783, 17206.569835754082, 17262.136893155417, 17443.56391090233, 17542.736858947126, 17570.0, 12731.490740370375, 13684.52104176359, 13804.481507034217, 14262.57212283633, 14278.500252491216, 3018.411409775095, 3478.808665523395, 3483.529723487322, 3501.0, 3504.3692135415613, 91270.0, 123432.0, 123505.26699963488, 123946.38026809899, 125350.0, 13002.0, 13031.08782385224, 13137.60587182386, 13224.272665003517, 13241.933790330986, 19379.51551211231, 24716.13493527754, 24993.87527561264, 25066.0, 25066.749332506442, 7667.386609071261, 7821.256980586835, 7887.053553573608, 7920.970273864361, 7946.125926148119, 3929.2730844793805, 3977.6022397759994, 4017.3572228443895, 4030.556638769733, 4033.576474470197, 4168.374743788456, 4179.498460184736, 4191.706580539394, 4220.324748040361, 4250.744955302692, 923.9907600924039, 940.5438362394233, 951.538503825644, 952.0, 953.5661274120397, 634.8287409729135, 639.4404895716249, 650.4276236911583, 659.3867703036127, 665.2250128600224, 11351.943240283746, 11851.762964740828, 11909.10681698876, 11970.281783093042, 12002.459889305002, 647.252423450915, 655.0665301944709, 655.806537071565, 669.2838662631035, 683.7504310926575, 5316.282301889196, 5389.110796718497, 5449.937262233906, 5577.163425486128, 5676.744546495335, 66138.0, 84406.57796710977, 84484.57757711296, 86628.56685716657, 87880.0, 73954.63022684852, 93850.53074734582, 94472.52763736274, 95834.52082739682, 97466.0, 78018.60990695124, 78209.60895195487, 78302.21697782949, 79630.40739185299, 79909.60045199736, 10828.18788590843, 11197.72005699864, 11347.829782553434, 11371.090312774793, 11469.082473402275, 44120.55879441164, 44722.77638611786, 44743.881402965184, 44766.77616611896, 44824.32763508615, 53329.0, 53453.585569731884, 54023.0, 54033.45966540284, 54945.72527137339, 11701.765964680488, 13846.446142154202, 14040.368183431567, 14105.576832695035, 14154.575362739135, 15491.225438727794, 16329.346826127297, 17617.119144042492, 18509.629807403508, 18660.34688785886, 15951.202439878192, 16112.516624501284, 16766.413175538797, 16869.24088416049, 17140.22868970924, 16010.279537421076, 16596.751048734037, 16789.0, 16996.660066798348, 17094.91452542729, 16101.597460063593, 16413.58966025859, 16611.91694041522, 16902.661946761244, 16945.23746431438, 11443.25618834773, 11756.94121529387, 11896.464659090188, 12021.398930053638, 12275.447604857982, 12802.807957880825, 13475.19148851072, 13872.653183670489, 13879.583612491642, 13938.0], "value": 8282.444000905372, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 124.0, 170.0, 99.0, 143.0, 104.0, 148.0, 114.0, 159.0, 169.0], "value": 133.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 111.0, 111.0, 148.0, 112.0, 112.0, 114.0, 117.0, 129.0, 149.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 205.0, 202.0, 270.0, 256.0, 267.0, 281.0, 208.0, 197.0, 199.0], "value": 228.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 73.0, 90.0, 110.0, 86.0, 62.0, 62.0, 63.0, 76.0, 48.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 81.0, 71.0, 73.0, 71.0, 86.0, 72.0, 87.0, 78.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 89.0, 89.0, 94.0, 90.0, 99.0, 89.0, 89.0, 78.0, 134.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 98.0, 98.0, 97.0, 98.0, 99.0, 96.0, 96.0], "value": 97.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 93.0, 93.0, 90.0, 92.0, 92.0, 92.0, 90.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 51.0, 50.0, 52.0, 52.0, 51.0, 52.0, 58.0, 58.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 67.0, 56.0, 59.0, 59.0, 56.0, 57.0, 55.0, 55.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 83.0, 83.0, 85.0, 82.0, 84.0, 84.0, 82.0, 85.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 159.0, 154.0, 139.0, 146.0, 156.0, 162.0, 142.0, 186.0, 166.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 186.0, 190.0, 185.0, 182.0, 209.0, 235.0, 187.0, 184.0], "value": 192.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 66.0, 84.0, 100.0, 78.0, 70.0, 68.0, 69.0], "value": 74.1, "unit": "score"}]}]} 05:05:42 INFO - Return code: 0 05:05:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:42 INFO - # TBPL SUCCESS # 05:05:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:42 INFO - 2017-05-03 05:05:42.168 screenresolution[1660:10603] starting screenresolution argv=screenresolution get 05:05:42 INFO - 2017-05-03 05:05:42.173 screenresolution[1660:10603] Display 0: 1600x1200x32@60 05:05:42 INFO - 2017-05-03 05:05:42.179 screenresolution[1661:10606] starting screenresolution argv=screenresolution list 05:05:42 INFO - Available Modes on Display 0 05:05:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:42 INFO - Intel Iris: 05:05:42 INFO - Chipset Model: Intel Iris 05:05:42 INFO - Type: GPU 05:05:42 INFO - Bus: Built-In 05:05:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:42 INFO - Vendor: Intel (0x8086) 05:05:42 INFO - Device ID: 0x0a2e 05:05:42 INFO - Revision ID: 0x0009 05:05:42 INFO - Displays: 05:05:42 INFO - Display: 05:05:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:42 INFO - Display Serial Number: 1600x1200 60 05:05:42 INFO - Main Display: Yes 05:05:42 INFO - Mirror: Off 05:05:42 INFO - Online: Yes 05:05:42 INFO - Rotation: Supported 05:05:42 INFO - Return code: 0 05:05:42 INFO - Running post-action listener: _resource_record_post_action 05:05:42 INFO - [mozharness: 2017-05-03 12:05:42.428040Z] Finished run-tests step (success) 05:05:42 INFO - Running post-run listener: _resource_record_post_run 05:05:42 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40917504; Write bytes: 513051648; Read time: 496; Write time: 1981 05:05:42 INFO - TinderboxPrint: CPU usage
54.1% 05:05:42 INFO - TinderboxPrint: I/O read bytes / time
40,917,504 / 496 05:05:42 INFO - TinderboxPrint: I/O write bytes / time
513,051,648 / 1,981 05:05:42 INFO - TinderboxPrint: CPU idle
2,195.4 (45.9%) 05:05:42 INFO - TinderboxPrint: CPU system
964.0 (20.1%) 05:05:42 INFO - TinderboxPrint: CPU user
1,626.3 (34.0%) 05:05:42 INFO - TinderboxPrint: Swap in / out
249,511,936 / 0 05:05:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209783296; Write bytes: 212703232; Read time: 16180; Write time: 459 05:05:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35858944; Write bytes: 299168768; Read time: 462; Write time: 1518 05:05:42 INFO - Running post-run listener: _upload_blobber_files 05:05:42 INFO - Blob upload gear active. 05:05:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:05:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:44 INFO - (blobuploader) - INFO - Done attempting. 05:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:44 INFO - (blobuploader) - INFO - Done attempting. 05:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:45 INFO - (blobuploader) - INFO - Done attempting. 05:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:48 INFO - (blobuploader) - INFO - Done attempting. 05:05:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:48 INFO - Return code: 0 05:05:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81575a73e42700316112ef3e7ee5c812202080ab4b72a8f44c7627ad264c3db3f318c44d93664efd8e05eb71d88e0e7260ec96fb404fdfdf4da703c1ec776432", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc79e8cc0030bb8b091e48c3e91f84acf4202c0de1a6343bffc41f2172fc1a901c8a250a51cf83e2c3fddea295ca657cee421b7acb01dd2f304e7dfd97722019", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e891d3ce1d34495eb98510dde50a53d47581714347e3ad9d3908ecdfe3382c07dae28c10c3c7d128589ad84bdee1514c944268729f877fdd755c6ba07f9595dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611a3019ad81ce9250b7387942e34436f63f2118733a8a3ec2e9f34f641ce76aa2ba10985f49af0e03de84092fabd677ab7442e83b1fc8e861305aaf4a36d5b0"} 05:05:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:48 INFO - Contents: 05:05:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81575a73e42700316112ef3e7ee5c812202080ab4b72a8f44c7627ad264c3db3f318c44d93664efd8e05eb71d88e0e7260ec96fb404fdfdf4da703c1ec776432", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc79e8cc0030bb8b091e48c3e91f84acf4202c0de1a6343bffc41f2172fc1a901c8a250a51cf83e2c3fddea295ca657cee421b7acb01dd2f304e7dfd97722019", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e891d3ce1d34495eb98510dde50a53d47581714347e3ad9d3908ecdfe3382c07dae28c10c3c7d128589ad84bdee1514c944268729f877fdd755c6ba07f9595dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611a3019ad81ce9250b7387942e34436f63f2118733a8a3ec2e9f34f641ce76aa2ba10985f49af0e03de84092fabd677ab7442e83b1fc8e861305aaf4a36d5b0"} 05:05:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:48 INFO - Copying logs to upload dir... 05:05:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.880491 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-03 05:05:48.279342) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:48.283196) ========= 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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/81575a73e42700316112ef3e7ee5c812202080ab4b72a8f44c7627ad264c3db3f318c44d93664efd8e05eb71d88e0e7260ec96fb404fdfdf4da703c1ec776432", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc79e8cc0030bb8b091e48c3e91f84acf4202c0de1a6343bffc41f2172fc1a901c8a250a51cf83e2c3fddea295ca657cee421b7acb01dd2f304e7dfd97722019", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e891d3ce1d34495eb98510dde50a53d47581714347e3ad9d3908ecdfe3382c07dae28c10c3c7d128589ad84bdee1514c944268729f877fdd755c6ba07f9595dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611a3019ad81ce9250b7387942e34436f63f2118733a8a3ec2e9f34f641ce76aa2ba10985f49af0e03de84092fabd677ab7442e83b1fc8e861305aaf4a36d5b0"} build_url:https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/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/81575a73e42700316112ef3e7ee5c812202080ab4b72a8f44c7627ad264c3db3f318c44d93664efd8e05eb71d88e0e7260ec96fb404fdfdf4da703c1ec776432", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc79e8cc0030bb8b091e48c3e91f84acf4202c0de1a6343bffc41f2172fc1a901c8a250a51cf83e2c3fddea295ca657cee421b7acb01dd2f304e7dfd97722019", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e891d3ce1d34495eb98510dde50a53d47581714347e3ad9d3908ecdfe3382c07dae28c10c3c7d128589ad84bdee1514c944268729f877fdd755c6ba07f9595dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611a3019ad81ce9250b7387942e34436f63f2118733a8a3ec2e9f34f641ce76aa2ba10985f49af0e03de84092fabd677ab7442e83b1fc8e861305aaf4a36d5b0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:48.320085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:48.320444) ========= 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.JTuNQVFl7U/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.DXHjLLMpzy/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005118 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:48.371443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 05:05:48.371830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 05:05:48.372296) ========= ========= Total master_lag: 0.19 =========