builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0144 starttime: 1492185388.15 results: success (0) buildid: 20170414080306 builduid: fb73c9bafeb846668461cab204ee035c revision: 8bad8b5fcd989759ec1f726deef1cdca2102d390 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.147146) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.147610) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.147907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.174348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.174706) ========= 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.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-04-14 08:56:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 47.4M=0s 2017-04-14 08:56:28 (47.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143264 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.339385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.339753) ========= 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.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.023966 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:28.388026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 08:56:28.388384) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8bad8b5fcd989759ec1f726deef1cdca2102d390 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8bad8b5fcd989759ec1f726deef1cdca2102d390 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-04-14 08:56:28,511 truncating revision to first 12 chars 2017-04-14 08:56:28,511 Setting DEBUG logging. 2017-04-14 08:56:28,512 attempt 1/10 2017-04-14 08:56:28,512 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8bad8b5fcd98?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-14 08:56:29,185 unpacking tar archive at: mozilla-aurora-8bad8b5fcd98/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923777 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 08:56:29.538824) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:29.539234) ========= script_repo_revision: 8bad8b5fcd989759ec1f726deef1cdca2102d390 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:29.539663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:29.539955) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 08:56:29.558323) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-14 08:56:29.558617) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:56:29 INFO - MultiFileLogger online at 20170414 08:56:29 in /builds/slave/test 08:56:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:56:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:29 INFO - {'append_to_log': False, 08:56:29 INFO - 'base_work_dir': '/builds/slave/test', 08:56:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:56:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:29 INFO - 'branch': 'Mozilla-Aurora', 08:56:29 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:29 INFO - 'config_files': ('talos/mac_config.py',), 08:56:29 INFO - 'default_actions': ('clobber', 08:56:29 INFO - 'read-buildbot-config', 08:56:29 INFO - 'download-and-extract', 08:56:29 INFO - 'populate-webroot', 08:56:29 INFO - 'create-virtualenv', 08:56:29 INFO - 'install', 08:56:29 INFO - 'run-tests'), 08:56:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:29 INFO - 'download_minidump_stackwalk': True, 08:56:29 INFO - 'download_symbols': 'ondemand', 08:56:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:29 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:29 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:29 INFO - 'gecko_profile': False, 08:56:29 INFO - 'gecko_profile_interval': 0, 08:56:29 INFO - 'installer_path': 'installer.exe', 08:56:29 INFO - 'log_level': 'info', 08:56:29 INFO - 'log_name': 'talos', 08:56:29 INFO - 'log_to_console': True, 08:56:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:56:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:56:29 INFO - 'opt_config_files': (), 08:56:29 INFO - 'pip_index': False, 08:56:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:29 INFO - 'cmd': ('bash', 08:56:29 INFO - '-c', 08:56:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:29 INFO - 'enabled': True, 08:56:29 INFO - 'halt_on_failure': False, 08:56:29 INFO - 'name': 'check_screen_resolution'},), 08:56:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:29 INFO - 'cmd': ('bash', 08:56:29 INFO - '-c', 08:56:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:29 INFO - 'enabled': True, 08:56:29 INFO - 'halt_on_failure': False, 08:56:29 INFO - 'name': 'check_screen_resolution'},), 08:56:29 INFO - 'run_cmd_checks_enabled': True, 08:56:29 INFO - 'suite': 'dromaeojs-e10s', 08:56:29 INFO - 'system_bits': '32', 08:56:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:29 INFO - 'title': 't-yosemite-r7-0144', 08:56:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:29 INFO - 'use_talos_json': True, 08:56:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:29 INFO - 'work_dir': 'build'} 08:56:29 INFO - [mozharness: 2017-04-14 15:56:29.797052Z] Running clobber step. 08:56:29 INFO - Running pre-action listener: _resource_record_pre_action 08:56:29 INFO - Running main action method: clobber 08:56:29 INFO - rmtree: /builds/slave/test/build 08:56:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:32 INFO - Running post-action listener: _resource_record_post_action 08:56:32 INFO - [mozharness: 2017-04-14 15:56:32.462540Z] Finished clobber step (success) 08:56:32 INFO - [mozharness: 2017-04-14 15:56:32.462690Z] Running read-buildbot-config step. 08:56:32 INFO - Running pre-action listener: _resource_record_pre_action 08:56:32 INFO - Running main action method: read_buildbot_config 08:56:32 INFO - Using buildbot properties: 08:56:32 INFO - { 08:56:32 INFO - "project": "", 08:56:32 INFO - "product": "firefox", 08:56:32 INFO - "script_repo_revision": "production", 08:56:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:56:32 INFO - "repository": "", 08:56:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:56:32 INFO - "buildid": "20170414080306", 08:56:32 INFO - "pgo_build": "False", 08:56:32 INFO - "basedir": "/builds/slave/test", 08:56:32 INFO - "buildnumber": 17, 08:56:32 INFO - "slavename": "t-yosemite-r7-0144", 08:56:32 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:56:32 INFO - "platform": "yosemite_r7", 08:56:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:56:32 INFO - "branch": "mozilla-aurora", 08:56:32 INFO - "repo_path": "releases/mozilla-aurora", 08:56:32 INFO - "revision": "8bad8b5fcd989759ec1f726deef1cdca2102d390", 08:56:32 INFO - "stage_platform": "macosx64", 08:56:32 INFO - "builduid": "fb73c9bafeb846668461cab204ee035c", 08:56:32 INFO - "slavebuilddir": "test" 08:56:32 INFO - } 08:56:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:56:32 INFO - Running post-action listener: _resource_record_post_action 08:56:32 INFO - [mozharness: 2017-04-14 15:56:32.466012Z] Finished read-buildbot-config step (success) 08:56:32 INFO - [mozharness: 2017-04-14 15:56:32.466128Z] Running download-and-extract step. 08:56:32 INFO - Running pre-action listener: _resource_record_pre_action 08:56:32 INFO - Running main action method: download_and_extract 08:56:32 INFO - mkdir: /builds/slave/test/build/tests 08:56: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')]} 08:56:32 INFO - trying https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 08:56:33 INFO - Downloaded 1199 bytes. 08:56:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:33 INFO - Using the following test package requirements: 08:56:33 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:56:33 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:56:33 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:56:33 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'jsshell-mac.zip'], 08:56:33 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:56:33 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:56:33 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:56:33 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:56:33 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:56:33 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:33 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:56:33 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:56:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:56:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:56:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:56:35 INFO - Expected file size: 51790941 08:56:35 INFO - Obtained file size: 51790941 08:56:51 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:56:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:56:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:56:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:56:53 INFO - Expected file size: 51790941 08:56:53 INFO - Obtained file size: 51790941 08:57:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:57:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:57:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:57:10 INFO - Expected file size: 14404424 08:57:10 INFO - Obtained file size: 14404424 08:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:10 INFO - trying https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 08:57:13 INFO - Downloaded 74729181 bytes. 08:57:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:13 INFO - mkdir: /builds/slave/test/properties 08:57:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:57:13 INFO - Writing to file /builds/slave/test/properties/build_url 08:57:13 INFO - Contents: 08:57:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:14 INFO - Running post-action listener: _resource_record_post_action 08:57:14 INFO - Running post-action listener: set_extra_try_arguments 08:57:14 INFO - [mozharness: 2017-04-14 15:57:14.230793Z] Finished download-and-extract step (success) 08:57:14 INFO - [mozharness: 2017-04-14 15:57:14.230952Z] Running populate-webroot step. 08:57:14 INFO - Running pre-action listener: _resource_record_pre_action 08:57:14 INFO - Running main action method: populate_webroot 08:57:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:57:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:57:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:57:14 INFO - '--tppagecycles', 08:57:14 INFO - '7'], 08:57:14 INFO - 'tests': ['tsvgm']}}, 08:57:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:57:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:57:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:57:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:57:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['damp', 'tps']}, 08:57:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'tests': ['damp', 'tps']}, 08:57:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['dromaeo_dom']}, 08:57:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:57:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['basic_compositor_video']}, 08:57:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:57:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'other-e10s': {'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['a11yr', 08:57:14 INFO - 'ts_paint', 08:57:14 INFO - 'tpaint', 08:57:14 INFO - 'sessionrestore', 08:57:14 INFO - 'sessionrestore_no_auto_restore', 08:57:14 INFO - 'tabpaint']}, 08:57:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['tsvgx', 08:57:14 INFO - 'tsvgr_opacity', 08:57:14 INFO - 'tart', 08:57:14 INFO - 'tscrollx', 08:57:14 INFO - 'cart', 08:57:14 INFO - 'tsvg_static']}, 08:57:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:57:14 INFO - 'tsvgr_opacity', 08:57:14 INFO - 'tart', 08:57:14 INFO - 'tscrollx', 08:57:14 INFO - 'cart', 08:57:14 INFO - 'tsvg_static']}, 08:57:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'talos_options': ['--disable-e10s'], 08:57:14 INFO - 'tests': ['tp5o']}, 08:57:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:57:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'talos_options': ['--disable-e10s', 08:57:14 INFO - '--xperf_path', 08:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:14 INFO - 'tests': ['tp5n']}, 08:57:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:57:14 INFO - 'talos_options': ['--xperf_path', 08:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:14 INFO - 'tests': ['tp5n']}}, 08:57:14 INFO - 'talos.zip': {'path': '', 08:57:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:57:14 INFO - Running post-action listener: _resource_record_post_action 08:57:14 INFO - [mozharness: 2017-04-14 15:57:14.249278Z] Finished populate-webroot step (success) 08:57:14 INFO - [mozharness: 2017-04-14 15:57:14.249409Z] Running create-virtualenv step. 08:57:14 INFO - Running pre-action listener: _resource_record_pre_action 08:57:14 INFO - Running main action method: create_virtualenv 08:57:14 INFO - Creating virtualenv /builds/slave/test/build/venv 08:57:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:57:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:57:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:57:14 INFO - Using real prefix '/tools/python27' 08:57:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:57:15 INFO - Installing distribute.............................................................................................................................................................................................done. 08:57:17 INFO - Installing pip.................done. 08:57:17 INFO - Return code: 0 08:57:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:57:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:57:18 INFO - Reading from file tmpfile_stdout 08:57:18 INFO - Output received: 08:57:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:57:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:18 INFO - 'HOME': '/Users/cltbld', 08:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:18 INFO - 'LOGNAME': 'cltbld', 08:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:18 INFO - 'MOZ_NO_REMOTE': '1', 08:57:18 INFO - 'NO_EM_RESTART': '1', 08:57:18 INFO - 'PAGER': '/bin/cat', 08:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:18 INFO - 'PWD': '/builds/slave/test', 08:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:18 INFO - 'SHELL': '/bin/bash', 08:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:18 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:18 INFO - 'USER': 'cltbld', 08:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:18 INFO - 'XPC_FLAGS': '0x0', 08:57:18 INFO - 'XPC_SERVICE_NAME': '0', 08:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:57:18 INFO - Cleaning up... 08:57:18 INFO - Return code: 0 08:57:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:18 INFO - 'HOME': '/Users/cltbld', 08:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:18 INFO - 'LOGNAME': 'cltbld', 08:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:18 INFO - 'MOZ_NO_REMOTE': '1', 08:57:18 INFO - 'NO_EM_RESTART': '1', 08:57:18 INFO - 'PAGER': '/bin/cat', 08:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:18 INFO - 'PWD': '/builds/slave/test', 08:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:18 INFO - 'SHELL': '/bin/bash', 08:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:18 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:18 INFO - 'USER': 'cltbld', 08:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:18 INFO - 'XPC_FLAGS': '0x0', 08:57:18 INFO - 'XPC_SERVICE_NAME': '0', 08:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:18 INFO - Downloading/unpacking psutil>=3.1.1 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:57:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:22 INFO - Installing collected packages: psutil 08:57:22 INFO - Running setup.py install for psutil 08:57:22 INFO - building 'psutil._psutil_osx' extension 08:57:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:57:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:57:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:57:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:57:23 INFO - building 'psutil._psutil_posix' extension 08:57:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:57:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:57:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:57:23 INFO - ^ 08:57:23 INFO - 1 warning generated. 08:57:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:57:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:23 INFO - Successfully installed psutil 08:57:23 INFO - Cleaning up... 08:57:23 INFO - Return code: 0 08:57:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:57: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 mozsystemmonitor==0.3 08:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:24 INFO - 'HOME': '/Users/cltbld', 08:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:24 INFO - 'LOGNAME': 'cltbld', 08:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:24 INFO - 'MOZ_NO_REMOTE': '1', 08:57:24 INFO - 'NO_EM_RESTART': '1', 08:57:24 INFO - 'PAGER': '/bin/cat', 08:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:24 INFO - 'PWD': '/builds/slave/test', 08:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:24 INFO - 'SHELL': '/bin/bash', 08:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:24 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:24 INFO - 'USER': 'cltbld', 08:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:24 INFO - 'XPC_FLAGS': '0x0', 08:57:24 INFO - 'XPC_SERVICE_NAME': '0', 08:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:57:27 INFO - Installing collected packages: mozsystemmonitor 08:57:27 INFO - Running setup.py install for mozsystemmonitor 08:57:27 INFO - Successfully installed mozsystemmonitor 08:57:27 INFO - Cleaning up... 08:57:27 INFO - Return code: 0 08:57:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:27 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:27 INFO - 'HOME': '/Users/cltbld', 08:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:27 INFO - 'LOGNAME': 'cltbld', 08:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:27 INFO - 'MOZ_NO_REMOTE': '1', 08:57:27 INFO - 'NO_EM_RESTART': '1', 08:57:27 INFO - 'PAGER': '/bin/cat', 08:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:27 INFO - 'PWD': '/builds/slave/test', 08:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:27 INFO - 'SHELL': '/bin/bash', 08:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:27 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:27 INFO - 'USER': 'cltbld', 08:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:27 INFO - 'XPC_FLAGS': '0x0', 08:57:27 INFO - 'XPC_SERVICE_NAME': '0', 08:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:27 INFO - Downloading/unpacking jsonschema==2.5.1 08:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:31 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:31 INFO - warning: no files found matching '*.txt' 08:57:31 INFO - no previously-included directories found matching 'build' 08:57:31 INFO - no previously-included directories found matching 'dist' 08:57:31 INFO - no previously-included directories found matching '.git*' 08:57:31 INFO - Installing collected packages: jsonschema, functools32 08:57:31 INFO - Running setup.py install for functools32 08:57:31 INFO - warning: no files found matching '*.txt' 08:57:31 INFO - no previously-included directories found matching 'build' 08:57:31 INFO - no previously-included directories found matching 'dist' 08:57:31 INFO - no previously-included directories found matching '.git*' 08:57:31 INFO - Successfully installed jsonschema functools32 08:57:31 INFO - Cleaning up... 08:57:31 INFO - Return code: 0 08:57:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:31 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:31 INFO - 'HOME': '/Users/cltbld', 08:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:31 INFO - 'LOGNAME': 'cltbld', 08:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:31 INFO - 'MOZ_NO_REMOTE': '1', 08:57:31 INFO - 'NO_EM_RESTART': '1', 08:57:31 INFO - 'PAGER': '/bin/cat', 08:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:31 INFO - 'PWD': '/builds/slave/test', 08:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:31 INFO - 'SHELL': '/bin/bash', 08:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:31 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:31 INFO - 'USER': 'cltbld', 08:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:31 INFO - 'XPC_FLAGS': '0x0', 08:57:31 INFO - 'XPC_SERVICE_NAME': '0', 08:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:31 INFO - Cleaning up... 08:57:31 INFO - Return code: 0 08:57:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:31 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:31 INFO - 'HOME': '/Users/cltbld', 08:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:31 INFO - 'LOGNAME': 'cltbld', 08:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:31 INFO - 'MOZ_NO_REMOTE': '1', 08:57:31 INFO - 'NO_EM_RESTART': '1', 08:57:31 INFO - 'PAGER': '/bin/cat', 08:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:31 INFO - 'PWD': '/builds/slave/test', 08:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:31 INFO - 'SHELL': '/bin/bash', 08:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:31 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:31 INFO - 'USER': 'cltbld', 08:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:31 INFO - 'XPC_FLAGS': '0x0', 08:57:31 INFO - 'XPC_SERVICE_NAME': '0', 08:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:34 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:35 INFO - Downloading docopt-0.6.1.tar.gz 08:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:36 INFO - Running setup.py install for blobuploader 08:57:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:36 INFO - Running setup.py install for requests 08:57:36 INFO - Running setup.py install for docopt 08:57:36 INFO - Successfully installed blobuploader requests docopt 08:57:36 INFO - Cleaning up... 08:57:36 INFO - Return code: 0 08:57:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57: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 08:57: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 08:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:36 INFO - 'HOME': '/Users/cltbld', 08:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:36 INFO - 'LOGNAME': 'cltbld', 08:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:36 INFO - 'MOZ_NO_REMOTE': '1', 08:57:36 INFO - 'NO_EM_RESTART': '1', 08:57:36 INFO - 'PAGER': '/bin/cat', 08:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:36 INFO - 'PWD': '/builds/slave/test', 08:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:36 INFO - 'SHELL': '/bin/bash', 08:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:36 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:36 INFO - 'USER': 'cltbld', 08:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:36 INFO - 'XPC_FLAGS': '0x0', 08:57:36 INFO - 'XPC_SERVICE_NAME': '0', 08:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-GhF18p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Bhag7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-qH6nWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-5YuwvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-vNd5Bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-qKryqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-KHzyn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:37 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-lHFc_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-AFTCPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-B3dF5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-aPwa0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8JzIP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-UYD2vV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-4FtMoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-sHjIex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-icdgS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:38 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-ikaLF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:38 INFO - Running setup.py install for manifestparser 08:57:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:39 INFO - Running setup.py install for mozcrash 08:57:39 INFO - Running setup.py install for mozdebug 08:57:39 INFO - Running setup.py install for mozdevice 08:57:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:57:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:39 INFO - Running setup.py install for mozfile 08:57:39 INFO - Running setup.py install for mozhttpd 08:57:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:39 INFO - Running setup.py install for mozinfo 08:57:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:39 INFO - Running setup.py install for mozInstall 08:57:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Running setup.py install for mozleak 08:57:40 INFO - Running setup.py install for mozlog 08:57:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Running setup.py install for moznetwork 08:57:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Running setup.py install for mozprocess 08:57:40 INFO - Running setup.py install for mozprofile 08:57:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:40 INFO - Running setup.py install for mozrunner 08:57:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:41 INFO - Running setup.py install for mozscreenshot 08:57:41 INFO - Running setup.py install for moztest 08:57:41 INFO - Running setup.py install for mozversion 08:57:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:41 INFO - Cleaning up... 08:57:41 INFO - Return code: 0 08:57:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57: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 08:57: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 08:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:41 INFO - 'HOME': '/Users/cltbld', 08:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:41 INFO - 'LOGNAME': 'cltbld', 08:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:41 INFO - 'MOZ_NO_REMOTE': '1', 08:57:41 INFO - 'NO_EM_RESTART': '1', 08:57:41 INFO - 'PAGER': '/bin/cat', 08:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:41 INFO - 'PWD': '/builds/slave/test', 08:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:41 INFO - 'SHELL': '/bin/bash', 08:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:41 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:41 INFO - 'USER': 'cltbld', 08:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:41 INFO - 'XPC_FLAGS': '0x0', 08:57:41 INFO - 'XPC_SERVICE_NAME': '0', 08:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8aoFp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57: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)) 08:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-fcMX6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57: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)) 08:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-tWRTVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-tdEEi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-O0O6Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-tpjAgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-XJyWmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57: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)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jouTnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57: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)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Bnvziv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57: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)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-A1MRn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57: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)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-qA5jmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57: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)) 08:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-QHwD4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:43 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-lWQaiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:43 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-QnGiFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:43 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-MVLDCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:43 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-uX6w_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57: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)) 08:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:43 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-NtfoMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57: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)) 08:57: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)) 08:57: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)) 08:57: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)) 08:57: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:46 INFO - Downloading blessings-1.6.tar.gz 08:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:46 INFO - Installing collected packages: blessings 08:57:46 INFO - Running setup.py install for blessings 08:57:46 INFO - Successfully installed blessings 08:57:46 INFO - Cleaning up... 08:57:46 INFO - Return code: 0 08:57:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:46 INFO - Reading from file tmpfile_stdout 08:57:46 INFO - Current package versions: 08:57:46 INFO - blessings == 1.6 08:57:46 INFO - blobuploader == 1.2.4 08:57:46 INFO - docopt == 0.6.1 08:57:46 INFO - functools32 == 3.2.3-2 08:57:46 INFO - jsonschema == 2.5.1 08:57:46 INFO - manifestparser == 1.1 08:57:46 INFO - mozInstall == 1.12 08:57:46 INFO - mozcrash == 1.0 08:57:46 INFO - mozdebug == 0.1 08:57:46 INFO - mozdevice == 0.49 08:57:46 INFO - mozfile == 1.2 08:57:46 INFO - mozhttpd == 0.7 08:57:46 INFO - mozinfo == 0.9 08:57:46 INFO - mozleak == 0.1 08:57:46 INFO - mozlog == 3.4 08:57:46 INFO - moznetwork == 0.27 08:57:46 INFO - mozprocess == 0.25 08:57:46 INFO - mozprofile == 0.28 08:57:46 INFO - mozrunner == 6.13 08:57:46 INFO - mozscreenshot == 0.1 08:57:46 INFO - mozsystemmonitor == 0.3 08:57:46 INFO - moztest == 0.8 08:57:46 INFO - mozversion == 1.4 08:57:46 INFO - psutil == 3.1.1 08:57:46 INFO - requests == 1.2.3 08:57:46 INFO - wsgiref == 0.1.2 08:57:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:46 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:46 INFO - 'HOME': '/Users/cltbld', 08:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:46 INFO - 'LOGNAME': 'cltbld', 08:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:46 INFO - 'MOZ_NO_REMOTE': '1', 08:57:46 INFO - 'NO_EM_RESTART': '1', 08:57:46 INFO - 'PAGER': '/bin/cat', 08:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:46 INFO - 'PWD': '/builds/slave/test', 08:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:46 INFO - 'SHELL': '/bin/bash', 08:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:46 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:46 INFO - 'USER': 'cltbld', 08:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:46 INFO - 'XPC_FLAGS': '0x0', 08:57:46 INFO - 'XPC_SERVICE_NAME': '0', 08:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:47 INFO - Cleaning up... 08:57:47 INFO - Return code: 0 08:57:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:47 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 0x104e9eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ea1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6bc9dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, '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.53hzWectZL/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.ax6KUJ69Yo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:57:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:57:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:47 INFO - 'HOME': '/Users/cltbld', 08:57:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:47 INFO - 'LOGNAME': 'cltbld', 08:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:47 INFO - 'MOZ_NO_REMOTE': '1', 08:57:47 INFO - 'NO_EM_RESTART': '1', 08:57:47 INFO - 'PAGER': '/bin/cat', 08:57:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:47 INFO - 'PWD': '/builds/slave/test', 08:57:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:47 INFO - 'SHELL': '/bin/bash', 08:57:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:57:47 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:57:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:47 INFO - 'USER': 'cltbld', 08:57:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:47 INFO - 'XPC_FLAGS': '0x0', 08:57:47 INFO - 'XPC_SERVICE_NAME': '0', 08:57:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:47 INFO - Cleaning up... 08:57:47 INFO - Return code: 0 08:57:47 INFO - Running post-action listener: _resource_record_post_action 08:57:47 INFO - Running post-action listener: _start_resource_monitoring 08:57:47 INFO - Starting resource monitoring. 08:57:47 INFO - [mozharness: 2017-04-14 15:57:47.466603Z] Finished create-virtualenv step (success) 08:57:47 INFO - [mozharness: 2017-04-14 15:57:47.467545Z] Running install step. 08:57:47 INFO - Running pre-action listener: _resource_record_pre_action 08:57:47 INFO - Running main action method: install 08:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:47 INFO - Reading from file tmpfile_stdout 08:57:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:47 INFO - Reading from file tmpfile_stdout 08:57:47 INFO - Output received: 08:57:47 INFO - Usage: mozinstall [options] installer 08:57:47 INFO - Options: 08:57:47 INFO - -h, --help show this help message and exit 08:57:47 INFO - -d DEST, --destination=DEST 08:57:47 INFO - Directory to install application into. [default: 08:57:47 INFO - "/builds/slave/test"] 08:57:47 INFO - --app=APP Application being installed. [default: firefox] 08:57:47 INFO - mkdir: /builds/slave/test/build/application 08:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:58:08 INFO - Reading from file tmpfile_stdout 08:58:08 INFO - Output received: 08:58:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:58:08 INFO - Running post-action listener: _resource_record_post_action 08:58:08 INFO - [mozharness: 2017-04-14 15:58:08.070275Z] Finished install step (success) 08:58:08 INFO - [mozharness: 2017-04-14 15:58:08.070427Z] Running run-tests step. 08:58:08 INFO - Running pre-action listener: _resource_record_pre_action 08:58:08 INFO - Running main action method: run_tests 08:58:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:58:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:58:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:58:08 INFO - 2017-04-14 08:58:08.084 screenresolution[1623:7402] starting screenresolution argv=screenresolution get 08:58:08 INFO - 2017-04-14 08:58:08.087 screenresolution[1623:7402] Display 0: 1600x1200x32@60 08:58:08 INFO - 2017-04-14 08:58:08.093 screenresolution[1624:7405] starting screenresolution argv=screenresolution list 08:58:08 INFO - Available Modes on Display 0 08:58:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:58:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:58:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:58:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:58:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:58:08 INFO - Intel Iris: 08:58:08 INFO - Chipset Model: Intel Iris 08:58:08 INFO - Type: GPU 08:58:08 INFO - Bus: Built-In 08:58:08 INFO - VRAM (Dynamic, Max): 1536 MB 08:58:08 INFO - Vendor: Intel (0x8086) 08:58:08 INFO - Device ID: 0x0a2e 08:58:08 INFO - Revision ID: 0x0009 08:58:08 INFO - Displays: 08:58:08 INFO - Display: 08:58:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:58:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:58:08 INFO - Display Serial Number: 1600x1200 60 08:58:08 INFO - Main Display: Yes 08:58:08 INFO - Mirror: Off 08:58:08 INFO - Online: Yes 08:58:08 INFO - Rotation: Supported 08:58:08 INFO - Return code: 0 08:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:58:08 INFO - Python 2.7.3 08:58:08 INFO - Return code: 0 08:58:08 INFO - grabbing minidump binary from tooltool 08:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:08 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 0x104c7cd30>, '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 0x104ba3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c71be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:58:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:58:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:58:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:58:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:58:08 INFO - Return code: 0 08:58:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:58:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:58:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:58:08 INFO - ENV: RUST_BACKTRACE is now 1 08:58:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:58:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0144 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.53hzWectZL/Render', 08:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:08 INFO - 'HOME': '/Users/cltbld', 08:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:08 INFO - 'LOGNAME': 'cltbld', 08:58:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:58:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:08 INFO - 'MOZ_NO_REMOTE': '1', 08:58:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:58:08 INFO - 'NO_EM_RESTART': '1', 08:58:08 INFO - 'PAGER': '/bin/cat', 08:58:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:08 INFO - 'PWD': '/builds/slave/test', 08:58:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:08 INFO - 'RUST_BACKTRACE': '1', 08:58:08 INFO - 'SHELL': '/bin/bash', 08:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ax6KUJ69Yo/Listeners', 08:58:08 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 08:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:08 INFO - 'USER': 'cltbld', 08:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:08 INFO - 'XPC_FLAGS': '0x0', 08:58:08 INFO - 'XPC_SERVICE_NAME': '0', 08:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:58:08 INFO - mozversion application_buildid: 20170414080306 08:58:08 INFO - mozversion application_changeset: 8bad8b5fcd989759ec1f726deef1cdca2102d390 08:58:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:58:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:58:08 INFO - mozversion application_name: Firefox 08:58:08 INFO - mozversion application_remotingname: firefox-dev 08:58:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:58:08 INFO - mozversion application_vendor: Mozilla 08:58:08 INFO - mozversion application_version: 54.0a2 08:58:08 INFO - mozversion platform_buildid: 20170414080306 08:58:08 INFO - mozversion platform_changeset: 8bad8b5fcd989759ec1f726deef1cdca2102d390 08:58:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:58:08 INFO - mozversion platform_version: 54.0a2 08:58:08 INFO - using testdate: 1492185488 08:58:08 INFO - actual date: 1492185488 08:58:08 INFO - starting webserver on 'localhost:49242' 08:58:08 INFO - SUITE-START | Running 2 tests 08:58:08 INFO - TEST-START | dromaeo_css 08:58:08 INFO - operating with platform_type : mac_ 08:58:08 INFO - Initialising browser for dromaeo_css test... 08:58:08 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpMy_Njz/profile http://localhost:49242/getInfo.html) 08:58:11 INFO - PROCESS | 1634 | 2017-04-14 08:58:11.823 plugin-container[1635:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 08:58:11 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:11 INFO - PROCESS | 1634 | 2017-04-14 08:58:11.826 plugin-container[1635:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 08:58:11 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:11 INFO - PROCESS | 1634 | 2017-04-14 08:58:11.826 plugin-container[1635:7582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:11 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:58:11 INFO - PROCESS | 1634 | colorDepth:24 08:58:11 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:58:11 INFO - PROCESS | 1634 | __metrics 08:58:12 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 08:58:12 INFO - TEST-INFO | 1634: exit 0 08:58:12 INFO - Browser initialized. 08:58:12 INFO - Running cycle 1/1 for dromaeo_css test... 08:58:12 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpMy_Njz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:15 INFO - PROCESS | 1637 | 2017-04-14 08:58:15.439 plugin-container[1638:7716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:58:15 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:15 INFO - PROCESS | 1637 | 2017-04-14 08:58:15.439 plugin-container[1638:7716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:58:15 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:15 INFO - PROCESS | 1637 | 2017-04-14 08:58:15.440 plugin-container[1638:7716] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:25 INFO - PROCESS | 1637 | RSS: Main: 220852224 08:58:25 INFO - PROCESS | 1637 | 09:01:28 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:01:30 INFO - PROCESS | 1637 | RSS: Main: 244396032 09:01:30 INFO - PROCESS | 1637 | 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:03:13 INFO - PROCESS | 1637 | 09:03:13 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:13 INFO - PROCESS | 1637 | Full stack: 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:03:13 INFO - PROCESS | 1637 | 09:03:13 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:13 INFO - PROCESS | 1637 | Full stack: 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:03:13 INFO - PROCESS | 1637 | 09:03:13 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:13 INFO - PROCESS | 1637 | Full stack: 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | ************************* 09:03:13 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:03:13 INFO - PROCESS | 1637 | 09:03:13 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:13 INFO - PROCESS | 1637 | Full stack: 09:03:13 INFO - PROCESS | 1637 | ************************* 09:04:32 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:04:33 INFO - PROCESS | 1637 | RSS: Main: 253325312 09:04:33 INFO - PROCESS | 1637 | 09:07:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:07:36 INFO - PROCESS | 1637 | RSS: Main: 259772416 09:07:36 INFO - PROCESS | 1637 | 09:10:38 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:10:38 INFO - PROCESS | 1637 | RSS: Main: 255946752 09:10:38 INFO - PROCESS | 1637 | 09:13:42 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:13:42 INFO - PROCESS | 1637 | RSS: Main: 260947968 09:13:42 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:16:45 INFO - PROCESS | 1637 | RSS: Main: 257200128 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | __start_tp_report 09:16:45 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 09:16:45 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 09:16:45 INFO - PROCESS | 1637 | |i|pagename|runs| 09:16:45 INFO - PROCESS | 1637 | |0;dojo.html;3475.3396854597627;3477.9304413911723;3494.986453928361;3504.614492405835;3505.5618047744033;6001.789937352193;6068.211132552768;6069.544784141191;6130.478909292704;6146.477549408294;6092.238470191226;6157.907631385521;6158.59969102008;6183.319834818167;6192.72132754026;4556.179887620227;5372.167314066326;5504.2294078829;5516.668999860001;5601.60788744788;5572.4706152915405;5581.776728930838;5587.1339942309005;5606.243157173791;5633.464820842013;4415.779211039451;5414.593905457087;5567.8608034799045;5582.051051321265;5584.636998595079;5534.03154447972;5544.22380866679;5583.6928968906695;5609.467100625434;5634.3520495143175;5381.623286369956;5512.310961129859;5538.695371754592;5539.030669632798;5578.274824272859;5851.502622277111;6133.233345831771;6142.447179753799;6167.506599472032;6228.37716228378;6331;6331.366863313678;6336.986082226822;6361.8409539761415;6373.808785736435;6493.870122597522;6508.414242718178;6513.706883190268;6533.314002029766;6537.901931471032;6184.783532576428;6231.40801623845;6262.3111457739615;6267.185265915402;6281.026440901714;6338.271098823602;6432.485401167895;6481.675916204171;6603.0095485676575;6740.29226931175;6311.495080393649;6319.431251187323;6400.359964003563;6407.903881441782;6418.550701450854;6519.478441724651;6539.149910511603;6546.738130474728;6551.246606640202;6564.540482166298;6287.842803929847;6360.681965901779;6456.418922297016;6471.546991710629;6486.902696459557;5980.581359304807;6298.50433009684;6304.590201636879;6308.968455157695;6325.9051114233325;5166.638335316492;5219.4780521947505;5266.104762190437;5282.577393808486;5293.947060529345;4591.4260717410325;4613.792379342937;4643.512431194744;4657.650676199299;4658.86023419298;6278.968605157036;6306.810795676137;6309.968450157719;6316.62100273985;6317.5893566918;6279.905801412982;6319.968400158062;6387.936120638734;6392.584482008655;6529.510286728514;5984.982552966007;6238.594491358047;6238.937610623926;6249.875002499924;6270.435660790459;4578.6794924355645;5778.56660750437;5780.71096445176;5802.738876750556;5803.7388317524965;6380.872382552416;6404.263509696445;6647.302033286435;6707.631080290631;6801.489888258301;5859.853503662443;6459;6464.547481676237;6531.738730450728;6565.704543295658;6502.187226596676;6818.18181818185;6823.829404264735;6827.48793840454;6828.146481689789;6135.263289243823;6236.937630623635;6284.842878928063;6335.334789847;6464.967675161594;6511.869762604816;6514.706838192198;6518.608883467007;6537.640429776217;6538.8365290868105;6352.269489008823;6359.395857393541;6386.584871983307;6389.137466442156;6399.8080057598345;5878.088896221093;6121.734958065366;6248.9062664059165;6312.936870631419;6376.489880809431;5368.677879327252;5578.525825304894;6135.018397056449;6142.170806941184;6335.049742538656;6287;6305.653189074645;6311.96844015777;6329.715162817776;6332.9366706334185;5168.896622067462;5225.216217567243;6105.45050945417;6116.633002019893;6244.250689917239 09:16:45 INFO - PROCESS | 1637 | |1;ext.html;744.4491076603312;798.0822054637167;819.6393586821799;948.7248697877616;960.3805545423202;37800;38631;39712.00719982002;39831.80084099583;39837.60162398375;38446.615533844655;38596.421053684215;38826.8058659707;38870;38908;7696;7880.921190788076;10401.479926003707;10699.090577300938;10833.945830270877;10584.259101862872;10664.200184986119;10684.091852192572;10715.142788576895;10753.408562529057;10706.571737130505;10707.411092389915;10718.732031699232;10746.355218686866;10757;10677.519511621995;10681.893181068246;10687.198460115522;10695.983881531241;10701.625443109493;10403.167746580255;10454;10462;10463;10465.633702820442;34187.487187692204;34346.82826585876;34548.82725586381;34585;34649;3651.3245049665898;3734.6265373462707;3741.214344987529;3742.850285988557;3782.5460944686815;24731.38171545708;24877.875610622013;25264.368390790376;26128.954841806306;26139.215823525134;3000.849957502116;3074.2621770775077;3146.3078122812944;3222.2266656002625;3258.7393008559498;305169;308666;308705;309627;309754;43665.56334436615;43682.78158609186;43743.34384984228;43751.68744937657;43762;36994.815025925236;45222.09555808931;45260;45280.77359613181;45480.772596136805;13427.530036448677;13790.517331893336;13804.723905521832;13865.376058077209;13874.098183618033;11916.404179791149;11930.761384772255;11960.58137965167;11970.58102966392;11989.700257493458;11432.88567114335;11967.880321196852;11992.64022079339;12009.219400738924;12086.033117350591;5497.285352904097;5610.915836262459;5613.045782217033;5617.101263797773;5635.549156067505;880.445319448756;1112.410422476089;1116.6918129119326;1120.1878637987395;1142.2689478733616;26758.60971765814;27060.323491912466;27083.72916270812;27187.86406067957;27261.591076133875;1000.8398656215116;1005.3766664667953;1211.1279878487476;1242.8570714367784;1250.856151542566;13408.262545560088;13734.27425597685;13811.309434528233;14037.017408781287;14046.508372206923;473270;475136;476563;478235;479113;47921.80195495141;49347.25979110316;55485;55583;56130.719346403006;1383.9100458470368;1397.8182836231429;1411.5976946570179;1412.6044707481922;1413.3993052952453;21280.893595531925;22417.551648967255;25887.3026000955;26786;27043.594346084432;41274;41357.58642413537;41419.34322627061;41449.585504145776;41523.7923810379;406086;452174.7391263154;455883;456061;456408;28801.847926082723;29144.979925702497;29759.80960761632;30423.84788076134;30788.846055769576;38140.80929595445;38578.6142138575;38928.41607375948;39025.414618780174;39496;21590.352289431343;27177.1846844595;27584.310392240353;27687.723122768515;28220.717792821808;27931.58102628503;27937.860310698317;27944.720552794213;28416;28667;32060.5190922141;32963.34073318472;32977.505337419476;33132;33302.33395332031;23367.76632233656;25261.74738252594;27646.861765691043;28288.00991965271;28469.145925622266;19471;22373.888130559244;22464.663030054235;23241.53516929618;23300.3009909703 09:16:45 INFO - PROCESS | 1637 | |2;jquery.html;343.97936123832574;372.8117300763115;404.8744889084382;406.30521807708817;448.8765589462897;14083;19794.7030794538;20161.697574536356;20941.790582094174;21010.36968890932;21307.25424610135;21425.892870535663;21987.34037978863;22334.553308933813;22539.436514087156;5990.191324171225;6721.932780672192;6925.861482770341;6963.825904352381;7041.8591628167405;6708.429783468387;6832.350926661959;6832.795016149524;6860.382565569098;6937.236903940562;6877;6888.070110535089;6899.827504312382;6907.654617269141;6919.031335613018;6600.867982640344;6810.659467026629;6886.345797149263;6901.378875901168;6905.792826215222;6460.095586617877;6639.3692599203;6680.899786503206;6735.191776986793;6735.22544907337;25650.230493085237;25854.741452585422;26310.86844565765;26388;26546;2957.0980850840483;2965.569992351119;3028;3133.9843300783577;3179.1416317594353;15251.779857611364;15791.842081579152;15954;16684.83315166845;17340.132993350282;2053.7330147080934;2072.3886453496257;2097.968530472044;2123.6814477828293;2143.164165975271;116172;140778.2961085188;143789;149149.25425372913;157161;20289.59420811605;21441.89279053595;21725.891370543046;21885.59258481566;21917.232896848534;27937.301567460978;29233.123006309845;32369.35261294761;32384.02847914566;32472.512912306334;9966.152877005536;10039.648612298535;10048.447335396624;10129.189664826796;10255.846162307571;8405.873911891327;8511.702090426805;8536.871946920677;8639.870401943976;8677.869831952527;9197.172254496994;9281.628734850665;9300.20948219409;9340.673076442428;9427.3400861939;3639.8180090995343;4228.154369126187;4330.679142861458;4342.5874541918465;4364.345348197732;892.9196372326522;1013.716159475348;1020.5815615597569;1034.363866222283;1076.434871692352;21157.9421028951;21318.934053297275;21459;21542.784572154393;21698.457538561663;780.5199802121655;925.8564922436898;945.4941606240628;976.5751897924425;1015.6546774096843;6779.966100169468;6797.456203503708;6823.28355522663;6988.266232045562;7036.261192574706;678679;738005;744922;753525;765479;124995.3750231243;144088;162931;167811;174372;1145.123980155182;1155.4511606986614;1294.4239813283186;1373.6359864635879;1379.2827729580563;12071.517139314328;12145.635630931087;12306;12481.93759031199;12568.49726010986;40680.796596018015;44540.772961351155;45464;62265.68867155635;65130;52809;85569.57215213885;88942.11057889338;89037.55481222553;89721.55139224262;7433.331000210007;9778.07108324708;10797.676069717922;11067.335959842436;11127.387993660102;12350.505979760708;12383.938080309541;12489.687757806127;12631.494740210288;12774.489020439078;10805.621803236849;11042.503087360927;11091.778164436828;11193.608223712132;11229.66311010671;10145.239107066853;10270.921553237102;10323.070923616911;10519.158467322442;10616.522256498321;11115.555377784798;11203.21577489568;11347.943260283646;11441.970222679998;11473.909978552025;11191.944040279746;11349.659510214708;11534.423278835857;11614.825777613174;11735.765284694087;9860.950695246765;10162.085412312927;10701.625443109453;10850.023497885228;10861 09:16:45 INFO - PROCESS | 1637 | |3;mootools.html;1422.317287701903;1537.6847746212027;1541;1561.1881821452844;1565.5068653374187;10796.73008174795;11137.387443690614;11381.03261222796;11394.487248073836;11473.311601303927;11088.445577721122;11238.66284011479;11250.493727782248;11287;11311.886881131186;2393.5811233034237;2434.160214725917;2441.377448750566;2443.2059580636364;2445.119756887519;2355.670206171129;2433.1483980606736;2438.9390265243333;2445.596476581362;2448.7306396296394;2314.2016004478514;2373.786359227669;2391.4380120671626;2405.025964484387;2419.4798118404537;2339.6373562097733;2393.856368617871;2404.8557086574774;2405.43472284014;2410.156445244168;1874.5875907300372;2208.436848603612;2217.456723102834;2221.888905554732;2228.855124416924;10753.78492430147;11143.609973650884;11156.721081973015;11170.888291117066;11187.216894817366;5817;5945.702714864239;6012.248468941382;6016;6020.759169633209;3510.947335789965;3537.893863184109;3590.5152804371337;3730.9440358394645;3762.567304759933;1204.0608325506118;1208.812634041725;1243.4528807324748;1251.8748125187408;1264.6016504800957;9826.771653543306;12841.614751557468;14664.706705865823;15324.157171355682;15435.459758908497;4693.78877950493;4795.544423279783;4807.423109226916;4840.2255639097575;4891.33966914462;11683.00694440982;11695.707607309885;11726.179167458196;11726.237794543327;11796;5692.658440493584;5773;5788.073908174756;5789.073748200268;5812.79655212074;5164.715940623302;5301.469853014745;5358.812441564526;5374.70439125844;5386.299781028441;3871.7676939383728;4005.959940400617;4016.9196616068098;4053.2096241233266;4075.735077219971;1064.4571268652887;1065.8188108021654;1071.3090056913404;1072.3405105859997;1072.7425417899804;921.456340758942;926.0600490502146;939.722781779363;945.546137853834;963.7976025034822;6474.741010359533;6556.967215163893;6568.482771598652;6617.867642647217;6764.797056088325;1004.372267332917;1024.513356155835;1042.1089968077335;1050.7478205230843;1050.985798704254;4185.958140418617;4273.978630106892;4291.914161716811;4403.229434848889;4410.6030457259;13806.309684515734;14166.716665666629;14314.570562883131;14364.066335688356;14427.278636068155;10138.543765530569;11455.770884582262;13922.373493192628;14075.366608502642;14313.141211527342;10011.649592264235;10066.547005384775;10296.794064118676;10302;10488.108510776518;5784.971075144597;6547.377999090078;6749.696263668245;6850.873749745464;6887.896681549681;8868.822623547621;9691.70924872255;10043.74890627711;12036.458359373673;12516.499340026661;11193.27243729155;11328.093752499615;12428.254304741744;12579.874201258237;12608.74782504363;9085.682001129928;9373.1564159226;9484.33609647318;9487.810243795224;9489.193418559224;10306.12397946183;10307.268573827792;10313.845292320471;10356;10504.212184086071;7562.621868906423;8867.157620026088;8896.822063558822;8910.59902304385;8938.195562399414;8555.31557475413;8568.742937711879;8750.47497150173;8794.42836215644;8897.777555561162;7650.120236172689;7882.09355924076;7921.683132674629;7945.562994035153;7973.20267973198;5794.536437084938;5926.259217597801;5993.850153746192;6040.818775436744;6215.223097112861;5316.548093412103;5906.497947674496;5956.195913551615;6129.662868542273;6178.289496707936 09:16:45 INFO - PROCESS | 1637 | |4;prototype.html;369.0901926750561;372.7130109815444;382.7473867247617;388.1131614261413;392.96659783918363;4592;4737.668363214576;4796.6882152660055;5245.265662807205;5364.624476286662;4375.868723938279;4743.312219728146;4876.658633895629;4881.975590122053;5109.489051094889;3682.2267323861956;3985.4221137934983;4436.134953684033;4439.955600443986;4480.910381792362;3471.427214509603;3596.82015899204;3881.825317860703;3899.181171953893;4488.708233964782;3896.844126234947;4167.187398457287;4371.650267978554;4407.757573333465;4447.577480139382;3751.737378383514;3872.7676339419586;4364.192624364487;4400.427944367212;4431.955680443187;3247.707706306444;3639.5078018012487;3780.9413364258053;3848.9230215395537;4090.897727556805;4229.91540169198;4814.398200224972;4870.732109733964;4937.7777999990085;5138.203578445347;4326.048269380732;4361.978190109029;4370.73248757863;4897.657163998521;5069.543741063285;4291.6266794625735;5046;5372.328458942632;5623.971880140614;5626.915596266059;3906.511686039245;4115.58844115589;4179.164167166578;4284.871453856389;4468.932966005513;5102.168296595388;5610.523105536035;5800.88398232033;5883.794067207627;5988.371221021817;3903.765774053566;3943.6788675793355;4544.477814812744;4571.474280457724;4576.908461830745;4465.24090904547;4663.761394530577;4679.883002924954;4682.344471773954;4693.741844198534;4190.559991200941;4210.915781684349;4263.699571630683;4743.0988112258565;5117.8720531987;4503.054358584668;4614.215583350839;5433.782648694087;5477;5598.608097433222;1461.4665647038755;1502.6243439140214;1534.4808639446867;1584.3979287870766;1584.8890577659454;1017.4912543728136;1096.6127848271942;1147.2370873369282;1179.6991767099335;1204.8313236146946;4950.801967921243;4983.626228032839;5167.896642067138;5256.553192978563;5261.394939581997;5199.948000520022;5298.562661175825;5479.698616576126;5805.10020946754;5810.738516766755;4560.8175672973375;4573.931391029138;4842.612590992712;5105.8723531912;5202.3728017350995;2747.697753247141;2910.563415487694;3005.8196508209576;3038.718184360885;3053.4656435124207;5224.615476898625;5525.336959564878;5698.461415417814;5767.653940763567;5918.644881307136;5311.123664595376;5483.698396588226;5505.834824955257;5887.676177810262;6014.699265036731;5464.890702185934;5568.972155139199;5578.30271216098;6085.330613632497;6131.202943617302;5090.705666107355;5316.494932981515;5746.867956963549;5794.652320860761;5885.823425297248;5763.913541296968;5783.806740113384;5798.0143375627085;5810.883782324246;5841.795537156179;5149.536541711264;5602.1036634138345;5755.568332375173;5833.854153646193;5999.580239263752;4041.91916161681;4253.255680256005;4660.184467718203;4685.976570117127;4720.026383378449;5558.555315574825;5603.523700485504;5665.575081868794;5687.606536398609;5756.258432019221;4705.847067468492;4851.417829860298;5111.105556527518;5460.645058071372;5696.803671229089;4891.437484689306;5447.809326673548;5621.494065534122;5642.463965923231;5669.574781891292;4807.879803004953;5260.526552610283;5489.39616642177;5562.193481945176;5626.606137570495;5664.433556644467;5685.317761868602;5691.69091109039;5694.743736531949;5831.35855055952;4725.314829349793;5044.772985215601;5287.841364759063;5549.528290095225;5557.861053473695 09:16:45 INFO - PROCESS | 1637 | |5;yui.html;616.328202259537;710.8542748736506;773.4933618479897;819.5986655380171;829.3489610655637;15105.320260588273;15584.298706558218;16083.35666573337;16165.83834161658;16359.672806543862;15083;15297.311620977054;15333.846661533382;16037.599060023476;16706.331746730117;936.283742936654;959.2997112108166;961.2550273537994;967.9884520675911;969.2391472693937;749.632679986808;946.0823001688351;956.550421301987;972.1687956796908;974.3763991045737;1000.3197825478671;1001.5593139018845;1002.4336250018753;1005.1807776662033;1006.7583779892847;984.3060642247199;995.581855620641;999.2405771613553;999.7000899730089;1000.2498126405196;732.739877343546;744.2668971063498;752.7779305105007;752.8381397999401;753.2128925273103;16092.034477931362;16338.918305408515;16376.83623163765;16823.747643785355;16956.66086678272;13938.93030534841;14099.859001409957;14142;14263.78604320936;14300.284985750672;11769.234999724991;11880.055316227783;11896.58361957336;11922;12129.332886691229;3102.5346198070242;3551.8579256829694;3597.676209141164;3633.8909832705062;3683.1712864605515;103329;105885.47057264665;106608;106619.99999999923;106929.39605905917;10154.949225253975;10589.894101058891;10662.573497060032;10741.731456713645;10783.676489705322;20198.899005505173;22524.54950900973;22794;22873.88563057174;22941.311760647208;6581.638009909405;6627.138471998609;6703.899441508382;6719.764808231688;6778.423833974069;3643.289558536113;4264.360345948071;4351.43431353922;4351.499577548622;4361.454818147731;3341.766076374679;3414.1293970037977;3473.878414255489;3479.1823921378455;3492.9695739756835;617.586217234454;628.1676778268812;633.7718421368304;644.8968165093562;647.439964430764;661.7683810666229;687.1994126842272;687.5049964025893;693.0470602920983;725.271102541942;9883.752906177258;10245.48772561369;10336.844947325797;10398.584056637801;10466.149499306111;687.0758829374483;711.3810984443567;716.989245161323;727.9781606551707;730.3645828129455;4713.363695901009;4856.368672072608;4881.243407271879;4892.706437613754;4916.434610019822;106554;108115.37826932603;110294;110305;111988.88011119784;43944.56055439469;54885;67699;79152.60423697844;94190;71424.57150856954;74285.62857185787;74917;75914.9999999989;75983.62008190034;10632.84050739224;10936.781264374828;10996.175286853359;11355.54577816878;11407.771844562896;36155.45766813447;46841.76579117082;46845.29732053954;47720.7613961928;48582.75708621434;55447.722761385936;60565.27456264391;66201.67596647944;66544.670936517;71226.28773712403;11023;12236.327002014974;12695.040038126719;12907.548235811702;12989.935050325066;14366.784498232744;14766.926165369105;15055.47305844334;15118.09291442517;15642.217889105272;11942.522299108286;13095.607131786062;13311.733765324445;13474.46102155942;13985;13080;13507.527236546674;13680.452781888613;13735;14160.578852752189;11830.940845295718;12941.482340706267;13363.53227637028;13440.663983400493;14017.929910350384;9298.395604285699;9350.76623084401;9644.565994530123;9907.256955728493;9947.900520994697;9999.950000249952;10136;10868.832302078263;11013.2841365311;11015.063719583604 09:16:45 INFO - PROCESS | 1637 | __end_tp_report 09:16:45 INFO - PROCESS | 1637 | __start_cc_report 09:16:45 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,420 09:16:45 INFO - PROCESS | 1637 | __end_cc_report 09:16:45 INFO - PROCESS | 1637 | __startTimestamp1492186605927__endTimestamp 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | ------- Summary: start ------- 09:16:45 INFO - PROCESS | 1637 | Number of tests: 6 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5992.82 Median:6262.31 stddev:679.00 (10.8%) stddev-sans-first:651.73 09:16:45 INFO - PROCESS | 1637 | Values: 3475.3 3477.9 3495.0 3504.6 3505.6 6001.8 6068.2 6069.5 6130.5 6146.5 6092.2 6157.9 6158.6 6183.3 6192.7 4556.2 5372.2 5504.2 5516.7 5601.6 5572.5 5581.8 5587.1 5606.2 5633.5 4415.8 5414.6 5567.9 5582.1 5584.6 5534.0 5544.2 5583.7 5609.5 5634.4 5381.6 5512.3 5538.7 5539.0 5578.3 5851.5 6133.2 6142.4 6167.5 6228.4 6331.0 6331.4 6337.0 6361.8 6373.8 6493.9 6508.4 6513.7 6533.3 6537.9 6184.8 6231.4 6262.3 6267.2 6281.0 6338.3 6432.5 6481.7 6603.0 6740.3 6311.5 6319.4 6400.4 6407.9 6418.6 6519.5 6539.1 6546.7 6551.2 6564.5 6287.8 6360.7 6456.4 6471.5 6486.9 5980.6 6298.5 6304.6 6309.0 6325.9 5166.6 5219.5 5266.1 5282.6 5293.9 4591.4 4613.8 4643.5 4657.7 4658.9 6279.0 6306.8 6310.0 6316.6 6317.6 6279.9 6320.0 6387.9 6392.6 6529.5 5985.0 6238.6 6238.9 6249.9 6270.4 4578.7 5778.6 5780.7 5802.7 5803.7 6380.9 6404.3 6647.3 6707.6 6801.5 5859.9 6459.0 6464.5 6531.7 6565.7 6502.2 6818.2 6823.8 6827.5 6828.1 6135.3 6236.9 6284.8 6335.3 6465.0 6511.9 6514.7 6518.6 6537.6 6538.8 6352.3 6359.4 6386.6 6389.1 6399.8 5878.1 6121.7 6248.9 6312.9 6376.5 5368.7 5578.5 6135.0 6142.2 6335.0 6287.0 6305.7 6312.0 6329.7 6332.9 5168.9 5225.2 6105.5 6116.6 6244.3 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:53519.11 Median:28568.07 stddev:110828.72 (387.9%) stddev-sans-first:111068.79 09:16:45 INFO - PROCESS | 1637 | Values: 744.4 798.1 819.6 948.7 960.4 37800.0 38631.0 39712.0 39831.8 39837.6 38446.6 38596.4 38826.8 38870.0 38908.0 7696.0 7880.9 10401.5 10699.1 10833.9 10584.3 10664.2 10684.1 10715.1 10753.4 10706.6 10707.4 10718.7 10746.4 10757.0 10677.5 10681.9 10687.2 10696.0 10701.6 10403.2 10454.0 10462.0 10463.0 10465.6 34187.5 34346.8 34548.8 34585.0 34649.0 3651.3 3734.6 3741.2 3742.9 3782.5 24731.4 24877.9 25264.4 26129.0 26139.2 3000.8 3074.3 3146.3 3222.2 3258.7 305169.0 308666.0 308705.0 309627.0 309754.0 43665.6 43682.8 43743.3 43751.7 43762.0 36994.8 45222.1 45260.0 45280.8 45480.8 13427.5 13790.5 13804.7 13865.4 13874.1 11916.4 11930.8 11960.6 11970.6 11989.7 11432.9 11967.9 11992.6 12009.2 12086.0 5497.3 5610.9 5613.0 5617.1 5635.5 880.4 1112.4 1116.7 1120.2 1142.3 26758.6 27060.3 27083.7 27187.9 27261.6 1000.8 1005.4 1211.1 1242.9 1250.9 13408.3 13734.3 13811.3 14037.0 14046.5 473270.0 475136.0 476563.0 478235.0 479113.0 47921.8 49347.3 55485.0 55583.0 56130.7 1383.9 1397.8 1411.6 1412.6 1413.4 21280.9 22417.6 25887.3 26786.0 27043.6 41274.0 41357.6 41419.3 41449.6 41523.8 406086.0 452174.7 455883.0 456061.0 456408.0 28801.8 29145.0 29759.8 30423.8 30788.8 38140.8 38578.6 38928.4 39025.4 39496.0 21590.4 27177.2 27584.3 27687.7 28220.7 27931.6 27937.9 27944.7 28416.0 28667.0 32060.5 32963.3 32977.5 33132.0 33302.3 23367.8 25261.7 27646.9 28288.0 28469.1 19471.0 22373.9 22464.7 23241.5 23300.3 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:41493.41 Median:24094.83 stddev:122829.92 (509.8%) stddev-sans-first:123135.63 09:16:45 INFO - PROCESS | 1637 | Values: 344.0 372.8 404.9 406.3 448.9 14083.0 19794.7 20161.7 20941.8 21010.4 21307.3 21425.9 21987.3 22334.6 22539.4 5990.2 6721.9 6925.9 6963.8 7041.9 6708.4 6832.4 6832.8 6860.4 6937.2 6877.0 6888.1 6899.8 6907.7 6919.0 6600.9 6810.7 6886.3 6901.4 6905.8 6460.1 6639.4 6680.9 6735.2 6735.2 25650.2 25854.7 26310.9 26388.0 26546.0 2957.1 2965.6 3028.0 3134.0 3179.1 15251.8 15791.8 15954.0 16684.8 17340.1 2053.7 2072.4 2098.0 2123.7 2143.2 116172.0 140778.3 143789.0 149149.3 157161.0 20289.6 21441.9 21725.9 21885.6 21917.2 27937.3 29233.1 32369.4 32384.0 32472.5 9966.2 10039.6 10048.4 10129.2 10255.8 8405.9 8511.7 8536.9 8639.9 8677.9 9197.2 9281.6 9300.2 9340.7 9427.3 3639.8 4228.2 4330.7 4342.6 4364.3 892.9 1013.7 1020.6 1034.4 1076.4 21157.9 21318.9 21459.0 21542.8 21698.5 780.5 925.9 945.5 976.6 1015.7 6780.0 6797.5 6823.3 6988.3 7036.3 678679.0 738005.0 744922.0 753525.0 765479.0 124995.4 144088.0 162931.0 167811.0 174372.0 1145.1 1155.5 1294.4 1373.6 1379.3 12071.5 12145.6 12306.0 12481.9 12568.5 40680.8 44540.8 45464.0 62265.7 65130.0 52809.0 85569.6 88942.1 89037.6 89721.6 7433.3 9778.1 10797.7 11067.3 11127.4 12350.5 12383.9 12489.7 12631.5 12774.5 10805.6 11042.5 11091.8 11193.6 11229.7 10145.2 10270.9 10323.1 10519.2 10616.5 11115.6 11203.2 11347.9 11442.0 11473.9 11191.9 11349.7 11534.4 11614.8 11735.8 9861.0 10162.1 10701.6 10850.0 10861.0 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6671.83 Median:2436.55 stddev:4100.90 (168.3%) stddev-sans-first:4093.43 09:16:45 INFO - PROCESS | 1637 | Values: 1422.3 1537.7 1541.0 1561.2 1565.5 10796.7 11137.4 11381.0 11394.5 11473.3 11088.4 11238.7 11250.5 11287.0 11311.9 2393.6 2434.2 2441.4 2443.2 2445.1 2355.7 2433.1 2438.9 2445.6 2448.7 2314.2 2373.8 2391.4 2405.0 2419.5 2339.6 2393.9 2404.9 2405.4 2410.2 1874.6 2208.4 2217.5 2221.9 2228.9 10753.8 11143.6 11156.7 11170.9 11187.2 5817.0 5945.7 6012.2 6016.0 6020.8 3510.9 3537.9 3590.5 3730.9 3762.6 1204.1 1208.8 1243.5 1251.9 1264.6 9826.8 12841.6 14664.7 15324.2 15435.5 4693.8 4795.5 4807.4 4840.2 4891.3 11683.0 11695.7 11726.2 11726.2 11796.0 5692.7 5773.0 5788.1 5789.1 5812.8 5164.7 5301.5 5358.8 5374.7 5386.3 3871.8 4006.0 4016.9 4053.2 4075.7 1064.5 1065.8 1071.3 1072.3 1072.7 921.5 926.1 939.7 945.5 963.8 6474.7 6557.0 6568.5 6617.9 6764.8 1004.4 1024.5 1042.1 1050.7 1051.0 4186.0 4274.0 4291.9 4403.2 4410.6 13806.3 14166.7 14314.6 14364.1 14427.3 10138.5 11455.8 13922.4 14075.4 14313.1 10011.6 10066.5 10296.8 10302.0 10488.1 5785.0 6547.4 6749.7 6850.9 6887.9 8868.8 9691.7 10043.7 12036.5 12516.5 11193.3 11328.1 12428.3 12579.9 12608.7 9085.7 9373.2 9484.3 9487.8 9489.2 10306.1 10307.3 10313.8 10356.0 10504.2 7562.6 8867.2 8896.8 8910.6 8938.2 8555.3 8568.7 8750.5 8794.4 8897.8 7650.1 7882.1 7921.7 7945.6 7973.2 5794.5 5926.3 5993.9 6040.8 6215.2 5316.5 5906.5 5956.2 6129.7 6178.3 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4600.16 Median:4879.32 stddev:1307.73 (26.8%) stddev-sans-first:1272.25 09:16:45 INFO - PROCESS | 1637 | Values: 369.1 372.7 382.7 388.1 393.0 4592.0 4737.7 4796.7 5245.3 5364.6 4375.9 4743.3 4876.7 4882.0 5109.5 3682.2 3985.4 4436.1 4440.0 4480.9 3471.4 3596.8 3881.8 3899.2 4488.7 3896.8 4167.2 4371.7 4407.8 4447.6 3751.7 3872.8 4364.2 4400.4 4432.0 3247.7 3639.5 3780.9 3848.9 4090.9 4229.9 4814.4 4870.7 4937.8 5138.2 4326.0 4362.0 4370.7 4897.7 5069.5 4291.6 5046.0 5372.3 5624.0 5626.9 3906.5 4115.6 4179.2 4284.9 4468.9 5102.2 5610.5 5800.9 5883.8 5988.4 3903.8 3943.7 4544.5 4571.5 4576.9 4465.2 4663.8 4679.9 4682.3 4693.7 4190.6 4210.9 4263.7 4743.1 5117.9 4503.1 4614.2 5433.8 5477.0 5598.6 1461.5 1502.6 1534.5 1584.4 1584.9 1017.5 1096.6 1147.2 1179.7 1204.8 4950.8 4983.6 5167.9 5256.6 5261.4 5199.9 5298.6 5479.7 5805.1 5810.7 4560.8 4573.9 4842.6 5105.9 5202.4 2747.7 2910.6 3005.8 3038.7 3053.5 5224.6 5525.3 5698.5 5767.7 5918.6 5311.1 5483.7 5505.8 5887.7 6014.7 5464.9 5569.0 5578.3 6085.3 6131.2 5090.7 5316.5 5746.9 5794.7 5885.8 5763.9 5783.8 5798.0 5810.9 5841.8 5149.5 5602.1 5755.6 5833.9 5999.6 4041.9 4253.3 4660.2 4686.0 4720.0 5558.6 5603.5 5665.6 5687.6 5756.3 4705.8 4851.4 5111.1 5460.6 5696.8 4891.4 5447.8 5621.5 5642.5 5669.6 4807.9 5260.5 5489.4 5562.2 5626.6 5664.4 5685.3 5691.7 5694.7 5831.4 4725.3 5044.8 5287.8 5549.5 5557.9 09:16:45 INFO - PROCESS | 1637 | 09:16:45 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19804.49 Median:3377.95 stddev:28430.15 (841.6%) stddev-sans-first:28473.39 09:16:45 INFO - PROCESS | 1637 | Values: 616.3 710.9 773.5 819.6 829.3 15105.3 15584.3 16083.4 16165.8 16359.7 15083.0 15297.3 15333.8 16037.6 16706.3 936.3 959.3 961.3 968.0 969.2 749.6 946.1 956.6 972.2 974.4 1000.3 1001.6 1002.4 1005.2 1006.8 984.3 995.6 999.2 999.7 1000.2 732.7 744.3 752.8 752.8 753.2 16092.0 16338.9 16376.8 16823.7 16956.7 13938.9 14099.9 14142.0 14263.8 14300.3 11769.2 11880.1 11896.6 11922.0 12129.3 3102.5 3551.9 3597.7 3633.9 3683.2 103329.0 105885.5 106608.0 106620.0 106929.4 10154.9 10589.9 10662.6 10741.7 10783.7 20198.9 22524.5 22794.0 22873.9 22941.3 6581.6 6627.1 6703.9 6719.8 6778.4 3643.3 4264.4 4351.4 4351.5 4361.5 3341.8 3414.1 3473.9 3479.2 3493.0 617.6 628.2 633.8 644.9 647.4 661.8 687.2 687.5 693.0 725.3 9883.8 10245.5 10336.8 10398.6 10466.1 687.1 711.4 717.0 728.0 730.4 4713.4 4856.4 4881.2 4892.7 4916.4 106554.0 108115.4 110294.0 110305.0 111988.9 43944.6 54885.0 67699.0 79152.6 94190.0 71424.6 74285.6 74917.0 75915.0 75983.6 10632.8 10936.8 10996.2 11355.5 11407.8 36155.5 46841.8 46845.3 47720.8 48582.8 55447.7 60565.3 66201.7 66544.7 71226.3 11023.0 12236.3 12695.0 12907.5 12989.9 14366.8 14766.9 15055.5 15118.1 15642.2 11942.5 13095.6 13311.7 13474.5 13985.0 13080.0 13507.5 13680.5 13735.0 14160.6 11830.9 12941.5 13363.5 13440.7 14017.9 9298.4 9350.8 9644.6 9907.3 9947.9 10000.0 10136.0 10868.8 11013.3 11015.1 09:16:45 INFO - PROCESS | 1637 | -------- Summary: end -------- 09:16:45 INFO - PROCESS | 1637 | 09:16:46 INFO - TEST-INFO | 1637: exit 0 09:16:46 INFO - TEST-OK | dromaeo_css | took 1117952ms 09:16:46 INFO - TEST-START | kraken 09:16:46 INFO - operating with platform_type : mac_ 09:16:46 INFO - Initialising browser for kraken test... 09:16:46 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpzfsd5s/profile http://localhost:49242/getInfo.html) 09:16:49 INFO - PROCESS | 1647 | 2017-04-14 09:16:49.367 plugin-container[1648:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a37, name = 'com.apple.tsm.portname' 09:16:49 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:49 INFO - PROCESS | 1647 | 2017-04-14 09:16:49.370 plugin-container[1648:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:16:49 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:49 INFO - PROCESS | 1647 | 2017-04-14 09:16:49.370 plugin-container[1648:10228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:49 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:16:49 INFO - PROCESS | 1647 | colorDepth:24 09:16:49 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:16:49 INFO - PROCESS | 1647 | __metrics 09:16:49 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:16:50 INFO - TEST-INFO | 1647: exit 0 09:16:50 INFO - Browser initialized. 09:16:50 INFO - Running cycle 1/1 for kraken test... 09:16:50 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpzfsd5s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:52 INFO - PROCESS | 1649 | 2017-04-14 09:16:52.891 plugin-container[1650:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:16:52 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:52 INFO - PROCESS | 1649 | 2017-04-14 09:16:52.891 plugin-container[1650:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:16:52 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:52 INFO - PROCESS | 1649 | 2017-04-14 09:16:52.891 plugin-container[1650:10355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:02 INFO - PROCESS | 1649 | RSS: Main: 220692480 09:17:02 INFO - PROCESS | 1649 | 09:17:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:17:06 INFO - PROCESS | 1649 | RSS: Main: 235241472 09:17:06 INFO - PROCESS | 1649 | 09:17:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:17:09 INFO - PROCESS | 1649 | RSS: Main: 243179520 09:17:09 INFO - PROCESS | 1649 | 09:17:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:17:13 INFO - PROCESS | 1649 | RSS: Main: 246726656 09:17:13 INFO - PROCESS | 1649 | 09:17:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:17:16 INFO - PROCESS | 1649 | RSS: Main: 248147968 09:17:16 INFO - PROCESS | 1649 | 09:17:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:17:19 INFO - PROCESS | 1649 | RSS: Main: 248545280 09:17:19 INFO - PROCESS | 1649 | 09:17:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:17:22 INFO - PROCESS | 1649 | RSS: Main: 252358656 09:17:22 INFO - PROCESS | 1649 | 09:17:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:17:26 INFO - PROCESS | 1649 | RSS: Main: 251969536 09:17:26 INFO - PROCESS | 1649 | 09:17:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:17:30 INFO - PROCESS | 1649 | RSS: Main: 251977728 09:17:30 INFO - PROCESS | 1649 | 09:17:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:32 INFO - PROCESS | 1649 | RSS: Main: 251678720 09:17:32 INFO - PROCESS | 1649 | 09:17:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:17:34 INFO - PROCESS | 1649 | RSS: Main: 251662336 09:17:34 INFO - PROCESS | 1649 | 09:17:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:17:37 INFO - PROCESS | 1649 | RSS: Main: 251768832 09:17:37 INFO - PROCESS | 1649 | 09:17:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:17:41 INFO - PROCESS | 1649 | RSS: Main: 252112896 09:17:41 INFO - PROCESS | 1649 | 09:17:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:45 INFO - PROCESS | 1649 | RSS: Main: 251867136 09:17:45 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:17:47 INFO - PROCESS | 1649 | RSS: Main: 253829120 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | __start_tp_report 09:17:47 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:17:47 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:17:47 INFO - PROCESS | 1649 | |i|pagename|runs| 09:17:47 INFO - PROCESS | 1649 | |0;ai-astar;160;115;179;159;121;123;121;121;120;121 09:17:47 INFO - PROCESS | 1649 | |1;audio-beat-detection;141;117;109;145;112;114;111;112;128;126 09:17:47 INFO - PROCESS | 1649 | |2;audio-dft;205;201;197;204;195;201;198;194;206;200 09:17:47 INFO - PROCESS | 1649 | |3;audio-fft;88;59;62;109;87;60;53;76;99;84 09:17:47 INFO - PROCESS | 1649 | |4;audio-oscillator;83;72;87;72;72;70;82;71;93;115 09:17:47 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;136;117;90;90;104;90;108;88;135;136 09:17:47 INFO - PROCESS | 1649 | |6;imaging-darkroom;97;97;97;95;97;97;96;98;97;104 09:17:47 INFO - PROCESS | 1649 | |7;imaging-desaturate;108;115;92;93;90;89;99;91;91;91 09:17:47 INFO - PROCESS | 1649 | |8;json-parse-financial;66;76;58;65;69;68;68;73;98;105 09:17:47 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;56;63;71;63;62;59;59;60;59;64 09:17:47 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;86;102;90;83;86;83;88;85;84;94 09:17:47 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;145;137;143;170;149;151;146;165;204;172 09:17:47 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;167;168;228;177;195;227;194;170;171;149 09:17:47 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;67;67;66;69;89;79;67;63;60 09:17:47 INFO - PROCESS | 1649 | __end_tp_report 09:17:47 INFO - PROCESS | 1649 | __start_cc_report 09:17:47 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1030 09:17:47 INFO - PROCESS | 1649 | __end_cc_report 09:17:47 INFO - PROCESS | 1649 | __startTimestamp1492186667325__endTimestamp 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:17:47 INFO - PROCESS | 1649 | Number of tests: 14 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:134.00 Median:122.00 stddev:22.80 (18.7%) stddev-sans-first:22.16 09:17:47 INFO - PROCESS | 1649 | Values: 160.0 115.0 179.0 159.0 121.0 123.0 121.0 121.0 120.0 121.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:121.50 stddev:12.99 (10.7%) stddev-sans-first:11.70 09:17:47 INFO - PROCESS | 1649 | Values: 141.0 117.0 109.0 145.0 112.0 114.0 111.0 112.0 128.0 126.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:200.10 Median:201.00 stddev:4.12 (2.1%) stddev-sans-first:3.97 09:17:47 INFO - PROCESS | 1649 | Values: 205.0 201.0 197.0 204.0 195.0 201.0 198.0 194.0 206.0 200.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:77.70 Median:80.00 stddev:18.82 (23.5%) stddev-sans-first:19.59 09:17:47 INFO - PROCESS | 1649 | Values: 88.0 59.0 62.0 109.0 87.0 60.0 53.0 76.0 99.0 84.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:77.00 stddev:14.14 (18.4%) stddev-sans-first:14.99 09:17:47 INFO - PROCESS | 1649 | Values: 83.0 72.0 87.0 72.0 72.0 70.0 82.0 71.0 93.0 115.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:112.00 stddev:20.40 (18.2%) stddev-sans-first:19.24 09:17:47 INFO - PROCESS | 1649 | Values: 136.0 117.0 90.0 90.0 104.0 90.0 108.0 88.0 135.0 136.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:97.00 stddev:2.42 (2.5%) stddev-sans-first:2.55 09:17:47 INFO - PROCESS | 1649 | Values: 97.0 97.0 97.0 95.0 97.0 97.0 96.0 98.0 97.0 104.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:91.00 stddev:8.81 (9.7%) stddev-sans-first:8.19 09:17:47 INFO - PROCESS | 1649 | Values: 108.0 115.0 92.0 93.0 90.0 89.0 99.0 91.0 91.0 91.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:74.60 Median:68.50 stddev:15.04 (22.0%) stddev-sans-first:15.63 09:17:47 INFO - PROCESS | 1649 | Values: 66.0 76.0 58.0 65.0 69.0 68.0 68.0 73.0 98.0 105.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.50 stddev:4.12 (6.6%) stddev-sans-first:3.83 09:17:47 INFO - PROCESS | 1649 | Values: 56.0 63.0 71.0 63.0 62.0 59.0 59.0 60.0 59.0 64.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:86.00 stddev:5.95 (6.9%) stddev-sans-first:6.26 09:17:47 INFO - PROCESS | 1649 | Values: 86.0 102.0 90.0 83.0 86.0 83.0 88.0 85.0 84.0 94.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:158.00 stddev:20.04 (12.7%) stddev-sans-first:20.68 09:17:47 INFO - PROCESS | 1649 | Values: 145.0 137.0 143.0 170.0 149.0 151.0 146.0 165.0 204.0 172.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:185.50 stddev:26.22 (14.1%) stddev-sans-first:27.02 09:17:47 INFO - PROCESS | 1649 | Values: 167.0 168.0 228.0 177.0 195.0 227.0 194.0 170.0 171.0 149.0 09:17:47 INFO - PROCESS | 1649 | 09:17:47 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.00 stddev:8.47 (12.6%) stddev-sans-first:8.90 09:17:47 INFO - PROCESS | 1649 | Values: 66.0 67.0 67.0 66.0 69.0 89.0 79.0 67.0 63.0 60.0 09:17:47 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:17:47 INFO - PROCESS | 1649 | 09:17:48 INFO - TEST-INFO | 1649: exit 0 09:17:48 INFO - TEST-OK | kraken | took 61651ms 09:17:48 INFO - SUITE-END | took 1179s 09:17:48 INFO - Completed test suite (00:19:39) 09:17:48 INFO - javascript benchmark 09:17:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7599.802372673124, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3475.3396854597627, 3477.9304413911723, 3494.986453928361, 3504.614492405835, 3505.5618047744033, 6001.789937352193, 6068.211132552768, 6069.544784141191, 6130.478909292704, 6146.477549408294, 6092.238470191226, 6157.907631385521, 6158.59969102008, 6183.319834818167, 6192.72132754026, 4556.179887620227, 5372.167314066326, 5504.2294078829, 5516.668999860001, 5601.60788744788, 5572.4706152915405, 5581.776728930838, 5587.1339942309005, 5606.243157173791, 5633.464820842013, 4415.779211039451, 5414.593905457087, 5567.8608034799045, 5582.051051321265, 5584.636998595079, 5534.03154447972, 5544.22380866679, 5583.6928968906695, 5609.467100625434, 5634.3520495143175, 5381.623286369956, 5512.310961129859, 5538.695371754592, 5539.030669632798, 5578.274824272859, 5851.502622277111, 6133.233345831771, 6142.447179753799, 6167.506599472032, 6228.37716228378, 6331.0, 6331.366863313678, 6336.986082226822, 6361.8409539761415, 6373.808785736435, 6493.870122597522, 6508.414242718178, 6513.706883190268, 6533.314002029766, 6537.901931471032, 6184.783532576428, 6231.40801623845, 6262.3111457739615, 6267.185265915402, 6281.026440901714, 6338.271098823602, 6432.485401167895, 6481.675916204171, 6603.0095485676575, 6740.29226931175, 6311.495080393649, 6319.431251187323, 6400.359964003563, 6407.903881441782, 6418.550701450854, 6519.478441724651, 6539.149910511603, 6546.738130474728, 6551.246606640202, 6564.540482166298, 6287.842803929847, 6360.681965901779, 6456.418922297016, 6471.546991710629, 6486.902696459557, 5980.581359304807, 6298.50433009684, 6304.590201636879, 6308.968455157695, 6325.9051114233325, 5166.638335316492, 5219.4780521947505, 5266.104762190437, 5282.577393808486, 5293.947060529345, 4591.4260717410325, 4613.792379342937, 4643.512431194744, 4657.650676199299, 4658.86023419298, 6278.968605157036, 6306.810795676137, 6309.968450157719, 6316.62100273985, 6317.5893566918, 6279.905801412982, 6319.968400158062, 6387.936120638734, 6392.584482008655, 6529.510286728514, 5984.982552966007, 6238.594491358047, 6238.937610623926, 6249.875002499924, 6270.435660790459, 4578.6794924355645, 5778.56660750437, 5780.71096445176, 5802.738876750556, 5803.7388317524965, 6380.872382552416, 6404.263509696445, 6647.302033286435, 6707.631080290631, 6801.489888258301, 5859.853503662443, 6459.0, 6464.547481676237, 6531.738730450728, 6565.704543295658, 6502.187226596676, 6818.18181818185, 6823.829404264735, 6827.48793840454, 6828.146481689789, 6135.263289243823, 6236.937630623635, 6284.842878928063, 6335.334789847, 6464.967675161594, 6511.869762604816, 6514.706838192198, 6518.608883467007, 6537.640429776217, 6538.8365290868105, 6352.269489008823, 6359.395857393541, 6386.584871983307, 6389.137466442156, 6399.8080057598345, 5878.088896221093, 6121.734958065366, 6248.9062664059165, 6312.936870631419, 6376.489880809431, 5368.677879327252, 5578.525825304894, 6135.018397056449, 6142.170806941184, 6335.049742538656, 6287.0, 6305.653189074645, 6311.96844015777, 6329.715162817776, 6332.9366706334185, 5168.896622067462, 5225.216217567243, 6105.45050945417, 6116.633002019893, 6244.250689917239], "value": 5950.481081731877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.4491076603312, 798.0822054637167, 819.6393586821799, 948.7248697877616, 960.3805545423202, 37800.0, 38631.0, 39712.00719982002, 39831.80084099583, 39837.60162398375, 38446.615533844655, 38596.421053684215, 38826.8058659707, 38870.0, 38908.0, 7696.0, 7880.921190788076, 10401.479926003707, 10699.090577300938, 10833.945830270877, 10584.259101862872, 10664.200184986119, 10684.091852192572, 10715.142788576895, 10753.408562529057, 10706.571737130505, 10707.411092389915, 10718.732031699232, 10746.355218686866, 10757.0, 10677.519511621995, 10681.893181068246, 10687.198460115522, 10695.983881531241, 10701.625443109493, 10403.167746580255, 10454.0, 10462.0, 10463.0, 10465.633702820442, 34187.487187692204, 34346.82826585876, 34548.82725586381, 34585.0, 34649.0, 3651.3245049665898, 3734.6265373462707, 3741.214344987529, 3742.850285988557, 3782.5460944686815, 24731.38171545708, 24877.875610622013, 25264.368390790376, 26128.954841806306, 26139.215823525134, 3000.849957502116, 3074.2621770775077, 3146.3078122812944, 3222.2266656002625, 3258.7393008559498, 305169.0, 308666.0, 308705.0, 309627.0, 309754.0, 43665.56334436615, 43682.78158609186, 43743.34384984228, 43751.68744937657, 43762.0, 36994.815025925236, 45222.09555808931, 45260.0, 45280.77359613181, 45480.772596136805, 13427.530036448677, 13790.517331893336, 13804.723905521832, 13865.376058077209, 13874.098183618033, 11916.404179791149, 11930.761384772255, 11960.58137965167, 11970.58102966392, 11989.700257493458, 11432.88567114335, 11967.880321196852, 11992.64022079339, 12009.219400738924, 12086.033117350591, 5497.285352904097, 5610.915836262459, 5613.045782217033, 5617.101263797773, 5635.549156067505, 880.445319448756, 1112.410422476089, 1116.6918129119326, 1120.1878637987395, 1142.2689478733616, 26758.60971765814, 27060.323491912466, 27083.72916270812, 27187.86406067957, 27261.591076133875, 1000.8398656215116, 1005.3766664667953, 1211.1279878487476, 1242.8570714367784, 1250.856151542566, 13408.262545560088, 13734.27425597685, 13811.309434528233, 14037.017408781287, 14046.508372206923, 473270.0, 475136.0, 476563.0, 478235.0, 479113.0, 47921.80195495141, 49347.25979110316, 55485.0, 55583.0, 56130.719346403006, 1383.9100458470368, 1397.8182836231429, 1411.5976946570179, 1412.6044707481922, 1413.3993052952453, 21280.893595531925, 22417.551648967255, 25887.3026000955, 26786.0, 27043.594346084432, 41274.0, 41357.58642413537, 41419.34322627061, 41449.585504145776, 41523.7923810379, 406086.0, 452174.7391263154, 455883.0, 456061.0, 456408.0, 28801.847926082723, 29144.979925702497, 29759.80960761632, 30423.84788076134, 30788.846055769576, 38140.80929595445, 38578.6142138575, 38928.41607375948, 39025.414618780174, 39496.0, 21590.352289431343, 27177.1846844595, 27584.310392240353, 27687.723122768515, 28220.717792821808, 27931.58102628503, 27937.860310698317, 27944.720552794213, 28416.0, 28667.0, 32060.5190922141, 32963.34073318472, 32977.505337419476, 33132.0, 33302.33395332031, 23367.76632233656, 25261.74738252594, 27646.861765691043, 28288.00991965271, 28469.145925622266, 19471.0, 22373.888130559244, 22464.663030054235, 23241.53516929618, 23300.3009909703], "value": 17909.66959810178, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.97936123832574, 372.8117300763115, 404.8744889084382, 406.30521807708817, 448.8765589462897, 14083.0, 19794.7030794538, 20161.697574536356, 20941.790582094174, 21010.36968890932, 21307.25424610135, 21425.892870535663, 21987.34037978863, 22334.553308933813, 22539.436514087156, 5990.191324171225, 6721.932780672192, 6925.861482770341, 6963.825904352381, 7041.8591628167405, 6708.429783468387, 6832.350926661959, 6832.795016149524, 6860.382565569098, 6937.236903940562, 6877.0, 6888.070110535089, 6899.827504312382, 6907.654617269141, 6919.031335613018, 6600.867982640344, 6810.659467026629, 6886.345797149263, 6901.378875901168, 6905.792826215222, 6460.095586617877, 6639.3692599203, 6680.899786503206, 6735.191776986793, 6735.22544907337, 25650.230493085237, 25854.741452585422, 26310.86844565765, 26388.0, 26546.0, 2957.0980850840483, 2965.569992351119, 3028.0, 3133.9843300783577, 3179.1416317594353, 15251.779857611364, 15791.842081579152, 15954.0, 16684.83315166845, 17340.132993350282, 2053.7330147080934, 2072.3886453496257, 2097.968530472044, 2123.6814477828293, 2143.164165975271, 116172.0, 140778.2961085188, 143789.0, 149149.25425372913, 157161.0, 20289.59420811605, 21441.89279053595, 21725.891370543046, 21885.59258481566, 21917.232896848534, 27937.301567460978, 29233.123006309845, 32369.35261294761, 32384.02847914566, 32472.512912306334, 9966.152877005536, 10039.648612298535, 10048.447335396624, 10129.189664826796, 10255.846162307571, 8405.873911891327, 8511.702090426805, 8536.871946920677, 8639.870401943976, 8677.869831952527, 9197.172254496994, 9281.628734850665, 9300.20948219409, 9340.673076442428, 9427.3400861939, 3639.8180090995343, 4228.154369126187, 4330.679142861458, 4342.5874541918465, 4364.345348197732, 892.9196372326522, 1013.716159475348, 1020.5815615597569, 1034.363866222283, 1076.434871692352, 21157.9421028951, 21318.934053297275, 21459.0, 21542.784572154393, 21698.457538561663, 780.5199802121655, 925.8564922436898, 945.4941606240628, 976.5751897924425, 1015.6546774096843, 6779.966100169468, 6797.456203503708, 6823.28355522663, 6988.266232045562, 7036.261192574706, 678679.0, 738005.0, 744922.0, 753525.0, 765479.0, 124995.3750231243, 144088.0, 162931.0, 167811.0, 174372.0, 1145.123980155182, 1155.4511606986614, 1294.4239813283186, 1373.6359864635879, 1379.2827729580563, 12071.517139314328, 12145.635630931087, 12306.0, 12481.93759031199, 12568.49726010986, 40680.796596018015, 44540.772961351155, 45464.0, 62265.68867155635, 65130.0, 52809.0, 85569.57215213885, 88942.11057889338, 89037.55481222553, 89721.55139224262, 7433.331000210007, 9778.07108324708, 10797.676069717922, 11067.335959842436, 11127.387993660102, 12350.505979760708, 12383.938080309541, 12489.687757806127, 12631.494740210288, 12774.489020439078, 10805.621803236849, 11042.503087360927, 11091.778164436828, 11193.608223712132, 11229.66311010671, 10145.239107066853, 10270.921553237102, 10323.070923616911, 10519.158467322442, 10616.522256498321, 11115.555377784798, 11203.21577489568, 11347.943260283646, 11441.970222679998, 11473.909978552025, 11191.944040279746, 11349.659510214708, 11534.423278835857, 11614.825777613174, 11735.765284694087, 9860.950695246765, 10162.085412312927, 10701.625443109453, 10850.023497885228, 10861.0], "value": 11092.393138368187, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.317287701903, 1537.6847746212027, 1541.0, 1561.1881821452844, 1565.5068653374187, 10796.73008174795, 11137.387443690614, 11381.03261222796, 11394.487248073836, 11473.311601303927, 11088.445577721122, 11238.66284011479, 11250.493727782248, 11287.0, 11311.886881131186, 2393.5811233034237, 2434.160214725917, 2441.377448750566, 2443.2059580636364, 2445.119756887519, 2355.670206171129, 2433.1483980606736, 2438.9390265243333, 2445.596476581362, 2448.7306396296394, 2314.2016004478514, 2373.786359227669, 2391.4380120671626, 2405.025964484387, 2419.4798118404537, 2339.6373562097733, 2393.856368617871, 2404.8557086574774, 2405.43472284014, 2410.156445244168, 1874.5875907300372, 2208.436848603612, 2217.456723102834, 2221.888905554732, 2228.855124416924, 10753.78492430147, 11143.609973650884, 11156.721081973015, 11170.888291117066, 11187.216894817366, 5817.0, 5945.702714864239, 6012.248468941382, 6016.0, 6020.759169633209, 3510.947335789965, 3537.893863184109, 3590.5152804371337, 3730.9440358394645, 3762.567304759933, 1204.0608325506118, 1208.812634041725, 1243.4528807324748, 1251.8748125187408, 1264.6016504800957, 9826.771653543306, 12841.614751557468, 14664.706705865823, 15324.157171355682, 15435.459758908497, 4693.78877950493, 4795.544423279783, 4807.423109226916, 4840.2255639097575, 4891.33966914462, 11683.00694440982, 11695.707607309885, 11726.179167458196, 11726.237794543327, 11796.0, 5692.658440493584, 5773.0, 5788.073908174756, 5789.073748200268, 5812.79655212074, 5164.715940623302, 5301.469853014745, 5358.812441564526, 5374.70439125844, 5386.299781028441, 3871.7676939383728, 4005.959940400617, 4016.9196616068098, 4053.2096241233266, 4075.735077219971, 1064.4571268652887, 1065.8188108021654, 1071.3090056913404, 1072.3405105859997, 1072.7425417899804, 921.456340758942, 926.0600490502146, 939.722781779363, 945.546137853834, 963.7976025034822, 6474.741010359533, 6556.967215163893, 6568.482771598652, 6617.867642647217, 6764.797056088325, 1004.372267332917, 1024.513356155835, 1042.1089968077335, 1050.7478205230843, 1050.985798704254, 4185.958140418617, 4273.978630106892, 4291.914161716811, 4403.229434848889, 4410.6030457259, 13806.309684515734, 14166.716665666629, 14314.570562883131, 14364.066335688356, 14427.278636068155, 10138.543765530569, 11455.770884582262, 13922.373493192628, 14075.366608502642, 14313.141211527342, 10011.649592264235, 10066.547005384775, 10296.794064118676, 10302.0, 10488.108510776518, 5784.971075144597, 6547.377999090078, 6749.696263668245, 6850.873749745464, 6887.896681549681, 8868.822623547621, 9691.70924872255, 10043.74890627711, 12036.458359373673, 12516.499340026661, 11193.27243729155, 11328.093752499615, 12428.254304741744, 12579.874201258237, 12608.74782504363, 9085.682001129928, 9373.1564159226, 9484.33609647318, 9487.810243795224, 9489.193418559224, 10306.12397946183, 10307.268573827792, 10313.845292320471, 10356.0, 10504.212184086071, 7562.621868906423, 8867.157620026088, 8896.822063558822, 8910.59902304385, 8938.195562399414, 8555.31557475413, 8568.742937711879, 8750.47497150173, 8794.42836215644, 8897.777555561162, 7650.120236172689, 7882.09355924076, 7921.683132674629, 7945.562994035153, 7973.20267973198, 5794.536437084938, 5926.259217597801, 5993.850153746192, 6040.818775436744, 6215.223097112861, 5316.548093412103, 5906.497947674496, 5956.195913551615, 6129.662868542273, 6178.289496707936], "value": 5129.463724347652, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.0901926750561, 372.7130109815444, 382.7473867247617, 388.1131614261413, 392.96659783918363, 4592.0, 4737.668363214576, 4796.6882152660055, 5245.265662807205, 5364.624476286662, 4375.868723938279, 4743.312219728146, 4876.658633895629, 4881.975590122053, 5109.489051094889, 3682.2267323861956, 3985.4221137934983, 4436.134953684033, 4439.955600443986, 4480.910381792362, 3471.427214509603, 3596.82015899204, 3881.825317860703, 3899.181171953893, 4488.708233964782, 3896.844126234947, 4167.187398457287, 4371.650267978554, 4407.757573333465, 4447.577480139382, 3751.737378383514, 3872.7676339419586, 4364.192624364487, 4400.427944367212, 4431.955680443187, 3247.707706306444, 3639.5078018012487, 3780.9413364258053, 3848.9230215395537, 4090.897727556805, 4229.91540169198, 4814.398200224972, 4870.732109733964, 4937.7777999990085, 5138.203578445347, 4326.048269380732, 4361.978190109029, 4370.73248757863, 4897.657163998521, 5069.543741063285, 4291.6266794625735, 5046.0, 5372.328458942632, 5623.971880140614, 5626.915596266059, 3906.511686039245, 4115.58844115589, 4179.164167166578, 4284.871453856389, 4468.932966005513, 5102.168296595388, 5610.523105536035, 5800.88398232033, 5883.794067207627, 5988.371221021817, 3903.765774053566, 3943.6788675793355, 4544.477814812744, 4571.474280457724, 4576.908461830745, 4465.24090904547, 4663.761394530577, 4679.883002924954, 4682.344471773954, 4693.741844198534, 4190.559991200941, 4210.915781684349, 4263.699571630683, 4743.0988112258565, 5117.8720531987, 4503.054358584668, 4614.215583350839, 5433.782648694087, 5477.0, 5598.608097433222, 1461.4665647038755, 1502.6243439140214, 1534.4808639446867, 1584.3979287870766, 1584.8890577659454, 1017.4912543728136, 1096.6127848271942, 1147.2370873369282, 1179.6991767099335, 1204.8313236146946, 4950.801967921243, 4983.626228032839, 5167.896642067138, 5256.553192978563, 5261.394939581997, 5199.948000520022, 5298.562661175825, 5479.698616576126, 5805.10020946754, 5810.738516766755, 4560.8175672973375, 4573.931391029138, 4842.612590992712, 5105.8723531912, 5202.3728017350995, 2747.697753247141, 2910.563415487694, 3005.8196508209576, 3038.718184360885, 3053.4656435124207, 5224.615476898625, 5525.336959564878, 5698.461415417814, 5767.653940763567, 5918.644881307136, 5311.123664595376, 5483.698396588226, 5505.834824955257, 5887.676177810262, 6014.699265036731, 5464.890702185934, 5568.972155139199, 5578.30271216098, 6085.330613632497, 6131.202943617302, 5090.705666107355, 5316.494932981515, 5746.867956963549, 5794.652320860761, 5885.823425297248, 5763.913541296968, 5783.806740113384, 5798.0143375627085, 5810.883782324246, 5841.795537156179, 5149.536541711264, 5602.1036634138345, 5755.568332375173, 5833.854153646193, 5999.580239263752, 4041.91916161681, 4253.255680256005, 4660.184467718203, 4685.976570117127, 4720.026383378449, 5558.555315574825, 5603.523700485504, 5665.575081868794, 5687.606536398609, 5756.258432019221, 4705.847067468492, 4851.417829860298, 5111.105556527518, 5460.645058071372, 5696.803671229089, 4891.437484689306, 5447.809326673548, 5621.494065534122, 5642.463965923231, 5669.574781891292, 4807.879803004953, 5260.526552610283, 5489.39616642177, 5562.193481945176, 5626.606137570495, 5664.433556644467, 5685.317761868602, 5691.69091109039, 5694.743736531949, 5831.35855055952, 4725.314829349793, 5044.772985215601, 5287.841364759063, 5549.528290095225, 5557.861053473695], "value": 4221.343107418635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.328202259537, 710.8542748736506, 773.4933618479897, 819.5986655380171, 829.3489610655637, 15105.320260588273, 15584.298706558218, 16083.35666573337, 16165.83834161658, 16359.672806543862, 15083.0, 15297.311620977054, 15333.846661533382, 16037.599060023476, 16706.331746730117, 936.283742936654, 959.2997112108166, 961.2550273537994, 967.9884520675911, 969.2391472693937, 749.632679986808, 946.0823001688351, 956.550421301987, 972.1687956796908, 974.3763991045737, 1000.3197825478671, 1001.5593139018845, 1002.4336250018753, 1005.1807776662033, 1006.7583779892847, 984.3060642247199, 995.581855620641, 999.2405771613553, 999.7000899730089, 1000.2498126405196, 732.739877343546, 744.2668971063498, 752.7779305105007, 752.8381397999401, 753.2128925273103, 16092.034477931362, 16338.918305408515, 16376.83623163765, 16823.747643785355, 16956.66086678272, 13938.93030534841, 14099.859001409957, 14142.0, 14263.78604320936, 14300.284985750672, 11769.234999724991, 11880.055316227783, 11896.58361957336, 11922.0, 12129.332886691229, 3102.5346198070242, 3551.8579256829694, 3597.676209141164, 3633.8909832705062, 3683.1712864605515, 103329.0, 105885.47057264665, 106608.0, 106619.99999999923, 106929.39605905917, 10154.949225253975, 10589.894101058891, 10662.573497060032, 10741.731456713645, 10783.676489705322, 20198.899005505173, 22524.54950900973, 22794.0, 22873.88563057174, 22941.311760647208, 6581.638009909405, 6627.138471998609, 6703.899441508382, 6719.764808231688, 6778.423833974069, 3643.289558536113, 4264.360345948071, 4351.43431353922, 4351.499577548622, 4361.454818147731, 3341.766076374679, 3414.1293970037977, 3473.878414255489, 3479.1823921378455, 3492.9695739756835, 617.586217234454, 628.1676778268812, 633.7718421368304, 644.8968165093562, 647.439964430764, 661.7683810666229, 687.1994126842272, 687.5049964025893, 693.0470602920983, 725.271102541942, 9883.752906177258, 10245.48772561369, 10336.844947325797, 10398.584056637801, 10466.149499306111, 687.0758829374483, 711.3810984443567, 716.989245161323, 727.9781606551707, 730.3645828129455, 4713.363695901009, 4856.368672072608, 4881.243407271879, 4892.706437613754, 4916.434610019822, 106554.0, 108115.37826932603, 110294.0, 110305.0, 111988.88011119784, 43944.56055439469, 54885.0, 67699.0, 79152.60423697844, 94190.0, 71424.57150856954, 74285.62857185787, 74917.0, 75914.9999999989, 75983.62008190034, 10632.84050739224, 10936.781264374828, 10996.175286853359, 11355.54577816878, 11407.771844562896, 36155.45766813447, 46841.76579117082, 46845.29732053954, 47720.7613961928, 48582.75708621434, 55447.722761385936, 60565.27456264391, 66201.67596647944, 66544.670936517, 71226.28773712403, 11023.0, 12236.327002014974, 12695.040038126719, 12907.548235811702, 12989.935050325066, 14366.784498232744, 14766.926165369105, 15055.47305844334, 15118.09291442517, 15642.217889105272, 11942.522299108286, 13095.607131786062, 13311.733765324445, 13474.46102155942, 13985.0, 13080.0, 13507.527236546674, 13680.452781888613, 13735.0, 14160.578852752189, 11830.940845295718, 12941.482340706267, 13363.53227637028, 13440.663983400493, 14017.929910350384, 9298.395604285699, 9350.76623084401, 9644.565994530123, 9907.256955728493, 9947.900520994697, 9999.950000249952, 10136.0, 10868.832302078263, 11013.2841365311, 11015.063719583604], "value": 7526.403861111931, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 115.0, 179.0, 159.0, 121.0, 123.0, 121.0, 121.0, 120.0, 121.0], "value": 134.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 109.0, 145.0, 112.0, 114.0, 111.0, 112.0, 128.0, 126.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 201.0, 197.0, 204.0, 195.0, 201.0, 198.0, 194.0, 206.0, 200.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 59.0, 62.0, 109.0, 87.0, 60.0, 53.0, 76.0, 99.0, 84.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 87.0, 72.0, 72.0, 70.0, 82.0, 71.0, 93.0, 115.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 117.0, 90.0, 90.0, 104.0, 90.0, 108.0, 88.0, 135.0, 136.0], "value": 109.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 95.0, 97.0, 97.0, 96.0, 98.0, 97.0, 104.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 92.0, 93.0, 90.0, 89.0, 99.0, 91.0, 91.0, 91.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 58.0, 65.0, 69.0, 68.0, 68.0, 73.0, 98.0, 105.0], "value": 74.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 71.0, 63.0, 62.0, 59.0, 59.0, 60.0, 59.0, 64.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 102.0, 90.0, 83.0, 86.0, 83.0, 88.0, 85.0, 84.0, 94.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 137.0, 143.0, 170.0, 149.0, 151.0, 146.0, 165.0, 204.0, 172.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 228.0, 177.0, 195.0, 227.0, 194.0, 170.0, 171.0, 149.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 69.0, 89.0, 79.0, 67.0, 63.0, 60.0], "value": 69.3, "unit": "score"}]}]} 09:17:48 INFO - Return code: 0 09:17:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:48 ERROR - # TBPL SUCCESS # 09:17:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:48 INFO - 2017-04-14 09:17:48.681 screenresolution[1654:10742] starting screenresolution argv=screenresolution get 09:17:48 INFO - 2017-04-14 09:17:48.683 screenresolution[1654:10742] Display 0: 1600x1200x32@60 09:17:48 INFO - 2017-04-14 09:17:48.690 screenresolution[1655:10745] starting screenresolution argv=screenresolution list 09:17:48 INFO - Available Modes on Display 0 09:17:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:48 INFO - Intel Iris: 09:17:48 INFO - Chipset Model: Intel Iris 09:17:48 INFO - Type: GPU 09:17:48 INFO - Bus: Built-In 09:17:48 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:48 INFO - Vendor: Intel (0x8086) 09:17:48 INFO - Device ID: 0x0a2e 09:17:48 INFO - Revision ID: 0x0009 09:17:48 INFO - Displays: 09:17:48 INFO - Display: 09:17:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:48 INFO - Display Serial Number: 1600x1200 60 09:17:48 INFO - Main Display: Yes 09:17:48 INFO - Mirror: Off 09:17:48 INFO - Online: Yes 09:17:48 INFO - Rotation: Supported 09:17:48 INFO - Return code: 0 09:17:48 INFO - Running post-action listener: _resource_record_post_action 09:17:48 INFO - [mozharness: 2017-04-14 16:17:48.932846Z] Finished run-tests step (success) 09:17:48 INFO - Running post-run listener: _resource_record_post_run 09:17:48 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 37698048; Write bytes: 507867648; Read time: 381; Write time: 1675 09:17:48 INFO - TinderboxPrint: CPU usage
54.6% 09:17:48 INFO - TinderboxPrint: I/O read bytes / time
37,698,048 / 381 09:17:48 INFO - TinderboxPrint: I/O write bytes / time
507,867,648 / 1,675 09:17:48 INFO - TinderboxPrint: CPU idle
2,180.0 (45.4%) 09:17:48 INFO - TinderboxPrint: CPU system
967.9 (20.2%) 09:17:48 INFO - TinderboxPrint: CPU user
1,653.8 (34.4%) 09:17:48 INFO - TinderboxPrint: Swap in / out
245,903,360 / 0 09:17:48 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201828864; Write bytes: 200902656; Read time: 15566; Write time: 407 09:17:49 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35867136; Write bytes: 296176128; Read time: 361; Write time: 1232 09:17:49 INFO - Running post-run listener: _upload_blobber_files 09:17:49 INFO - Blob upload gear active. 09:17:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:49 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 09:17:49 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:17:49 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:17:49 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:17:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:17:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:50 INFO - (blobuploader) - INFO - Done attempting. 09:17:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:17:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:17:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:52 INFO - (blobuploader) - INFO - Done attempting. 09:17:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:17:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:17:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:53 INFO - (blobuploader) - INFO - Done attempting. 09:17:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:56 INFO - (blobuploader) - INFO - Done attempting. 09:17:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:56 INFO - Return code: 0 09:17:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e32435eb6165b9ecb5f48f89aba16e4b5fa82cef48a5a9dbc4a2e469ced68f2e69e1c1d9354e3f8d23e26873775243f6e337ef9647db7038b5eb5f9b93e63b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e35178bc6482c5406c2e0eb063ba4a79474ee2d09ae6b15dcd1adfb6600010bbdb18bccbf5ed335b347cbecfc529bdf8d07f94b1ec90cc07cb12a535fade509", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6eacb14a342e7d14e7881e1902b25f105a54392ea6ae224e6fda0f3dc134324dc227588ef27d4d3e02e766ec2b0c2b52a5b2fa8439d714c1c02f43fb1dd191ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe04ad9ef2cdfa305bebc5f953f118f09138afbbebed121fff7ea4da372952730c7d146470ae754a0d05476adc12141ab833cbf71c614817cdfc21cf0711e4b5"} 09:17:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:56 INFO - Contents: 09:17:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e32435eb6165b9ecb5f48f89aba16e4b5fa82cef48a5a9dbc4a2e469ced68f2e69e1c1d9354e3f8d23e26873775243f6e337ef9647db7038b5eb5f9b93e63b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e35178bc6482c5406c2e0eb063ba4a79474ee2d09ae6b15dcd1adfb6600010bbdb18bccbf5ed335b347cbecfc529bdf8d07f94b1ec90cc07cb12a535fade509", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6eacb14a342e7d14e7881e1902b25f105a54392ea6ae224e6fda0f3dc134324dc227588ef27d4d3e02e766ec2b0c2b52a5b2fa8439d714c1c02f43fb1dd191ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe04ad9ef2cdfa305bebc5f953f118f09138afbbebed121fff7ea4da372952730c7d146470ae754a0d05476adc12141ab833cbf71c614817cdfc21cf0711e4b5"} 09:17:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:56 INFO - Copying logs to upload dir... 09:17:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.393051 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-14 09:17:57.215872) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:17:57.219283) ========= 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.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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/Mozilla-Aurora/sha512/e32435eb6165b9ecb5f48f89aba16e4b5fa82cef48a5a9dbc4a2e469ced68f2e69e1c1d9354e3f8d23e26873775243f6e337ef9647db7038b5eb5f9b93e63b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e35178bc6482c5406c2e0eb063ba4a79474ee2d09ae6b15dcd1adfb6600010bbdb18bccbf5ed335b347cbecfc529bdf8d07f94b1ec90cc07cb12a535fade509", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6eacb14a342e7d14e7881e1902b25f105a54392ea6ae224e6fda0f3dc134324dc227588ef27d4d3e02e766ec2b0c2b52a5b2fa8439d714c1c02f43fb1dd191ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe04ad9ef2cdfa305bebc5f953f118f09138afbbebed121fff7ea4da372952730c7d146470ae754a0d05476adc12141ab833cbf71c614817cdfc21cf0711e4b5"} build_url:https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/Fq0cmoSKTe2G4TO0J-ec_w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e32435eb6165b9ecb5f48f89aba16e4b5fa82cef48a5a9dbc4a2e469ced68f2e69e1c1d9354e3f8d23e26873775243f6e337ef9647db7038b5eb5f9b93e63b57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e35178bc6482c5406c2e0eb063ba4a79474ee2d09ae6b15dcd1adfb6600010bbdb18bccbf5ed335b347cbecfc529bdf8d07f94b1ec90cc07cb12a535fade509", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6eacb14a342e7d14e7881e1902b25f105a54392ea6ae224e6fda0f3dc134324dc227588ef27d4d3e02e766ec2b0c2b52a5b2fa8439d714c1c02f43fb1dd191ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe04ad9ef2cdfa305bebc5f953f118f09138afbbebed121fff7ea4da372952730c7d146470ae754a0d05476adc12141ab833cbf71c614817cdfc21cf0711e4b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:17:57.254317) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:17:57.254621) ========= 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.53hzWectZL/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.ax6KUJ69Yo/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:17:57.303888) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:17:57.304213) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:17:57.304620) ========= ========= Total master_lag: 0.60 =========