builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0165 starttime: 1487680674.42 results: success (0) buildid: 20170221034528 builduid: 4cbc1696c0fe4764bca5a8fdb7dc2ee5 revision: cc472627593465885a69a4c1c7e2fd8ad3787c48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:54.415480) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:54.415966) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:54.416298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:54.447475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:54.448170) ========= 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.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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-02-21 04:37:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2017-02-21 04:37:55 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.548151 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:55.025314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:55.026072) ========= 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.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.031198 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 04:37:55.080610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-02-21 04:37:55.080983) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cc472627593465885a69a4c1c7e2fd8ad3787c48 --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 cc472627593465885a69a4c1c7e2fd8ad3787c48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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-02-21 04:37:55,292 truncating revision to first 12 chars 2017-02-21 04:37:55,292 Setting DEBUG logging. 2017-02-21 04:37:55,292 attempt 1/10 2017-02-21 04:37:55,292 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cc4726275934?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 04:37:59,428 unpacking tar archive at: mozilla-aurora-cc4726275934/testing/mozharness/ program finished with exit code 0 elapsedTime=4.400651 ========= master_lag: 1.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-02-21 04:38:01.154623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 04:38:01.154941) ========= script_repo_revision: cc472627593465885a69a4c1c7e2fd8ad3787c48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 04:38:01.155347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 04:38:01.155603) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 04:38:01.187973) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 24 mins, 9 secs) (at 2017-02-21 04:38:01.188368) ========= /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.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:38:01 INFO - MultiFileLogger online at 20170221 04:38:01 in /builds/slave/test 04:38:01 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 04:38:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:01 INFO - {'append_to_log': False, 04:38:01 INFO - 'base_work_dir': '/builds/slave/test', 04:38:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:38:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:01 INFO - 'branch': 'Mozilla-Aurora', 04:38:01 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:01 INFO - 'config_files': ('talos/mac_config.py',), 04:38:01 INFO - 'default_actions': ('clobber', 04:38:01 INFO - 'read-buildbot-config', 04:38:01 INFO - 'download-and-extract', 04:38:01 INFO - 'populate-webroot', 04:38:01 INFO - 'create-virtualenv', 04:38:01 INFO - 'install', 04:38:01 INFO - 'run-tests'), 04:38:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:01 INFO - 'download_minidump_stackwalk': True, 04:38:01 INFO - 'download_symbols': 'ondemand', 04:38:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:01 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:01 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:01 INFO - 'installer_path': 'installer.exe', 04:38:01 INFO - 'log_level': 'info', 04:38:01 INFO - 'log_name': 'talos', 04:38:01 INFO - 'log_to_console': True, 04:38:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:38:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:38:01 INFO - 'opt_config_files': (), 04:38:01 INFO - 'pip_index': False, 04:38:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:01 INFO - 'cmd': ('bash', 04:38:01 INFO - '-c', 04:38:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:01 INFO - 'enabled': True, 04:38:01 INFO - 'halt_on_failure': False, 04:38:01 INFO - 'name': 'check_screen_resolution'},), 04:38:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:01 INFO - 'cmd': ('bash', 04:38:01 INFO - '-c', 04:38:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:01 INFO - 'enabled': True, 04:38:01 INFO - 'halt_on_failure': False, 04:38:01 INFO - 'name': 'check_screen_resolution'},), 04:38:01 INFO - 'run_cmd_checks_enabled': True, 04:38:01 INFO - 'sps_profile': False, 04:38:01 INFO - 'sps_profile_interval': 0, 04:38:01 INFO - 'suite': 'dromaeojs-e10s', 04:38:01 INFO - 'system_bits': '32', 04:38:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:01 INFO - 'title': 't-yosemite-r7-0165', 04:38:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:01 INFO - 'use_talos_json': True, 04:38:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:01 INFO - 'work_dir': 'build'} 04:38:01 INFO - [mozharness: 2017-02-21 12:38:01.513476Z] Running clobber step. 04:38:01 INFO - Running pre-action listener: _resource_record_pre_action 04:38:01 INFO - Running main action method: clobber 04:38:01 INFO - rmtree: /builds/slave/test/build 04:38:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:03 INFO - Running post-action listener: _resource_record_post_action 04:38:03 INFO - [mozharness: 2017-02-21 12:38:03.988535Z] Finished clobber step (success) 04:38:03 INFO - [mozharness: 2017-02-21 12:38:03.988672Z] Running read-buildbot-config step. 04:38:03 INFO - Running pre-action listener: _resource_record_pre_action 04:38:03 INFO - Running main action method: read_buildbot_config 04:38:03 INFO - Using buildbot properties: 04:38:03 INFO - { 04:38:03 INFO - "project": "", 04:38:03 INFO - "product": "firefox", 04:38:03 INFO - "script_repo_revision": "production", 04:38:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 04:38:03 INFO - "repository": "", 04:38:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 04:38:03 INFO - "buildid": "20170221034528", 04:38:03 INFO - "pgo_build": "False", 04:38:03 INFO - "basedir": "/builds/slave/test", 04:38:03 INFO - "buildnumber": 8, 04:38:03 INFO - "slavename": "t-yosemite-r7-0165", 04:38:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:38:03 INFO - "platform": "yosemite_r7", 04:38:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:38:03 INFO - "branch": "mozilla-aurora", 04:38:03 INFO - "repo_path": "releases/mozilla-aurora", 04:38:03 INFO - "revision": "cc472627593465885a69a4c1c7e2fd8ad3787c48", 04:38:03 INFO - "stage_platform": "macosx64", 04:38:03 INFO - "builduid": "4cbc1696c0fe4764bca5a8fdb7dc2ee5", 04:38:03 INFO - "slavebuilddir": "test" 04:38:03 INFO - } 04:38:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 04:38:03 INFO - Running post-action listener: _resource_record_post_action 04:38:03 INFO - [mozharness: 2017-02-21 12:38:03.992273Z] Finished read-buildbot-config step (success) 04:38:03 INFO - [mozharness: 2017-02-21 12:38:03.992354Z] Running download-and-extract step. 04:38:03 INFO - Running pre-action listener: _resource_record_pre_action 04:38:03 INFO - Running main action method: download_and_extract 04:38:03 INFO - mkdir: /builds/slave/test/build/tests 04:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:38:03 INFO - trying https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 04:38:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 04:38:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 04:38:07 INFO - Downloaded 1199 bytes. 04:38:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 04:38:07 INFO - Using the following test package requirements: 04:38:07 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 04:38:07 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 04:38:07 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 04:38:07 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'jsshell-mac.zip'], 04:38:07 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 04:38:07 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 04:38:07 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 04:38:07 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 04:38:07 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 04:38:07 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:38:07 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 04:38:07 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:38:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 04:38:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:38:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 04:38:12 INFO - Expected file size: 16357271 04:38:12 INFO - Obtained file size: 16357271 04:38:19 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:38:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 04:38:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:38:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 04:38:21 INFO - Expected file size: 16357271 04:38:21 INFO - Obtained file size: 16357271 04:38:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 04:38:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:38:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 04:39:02 INFO - retry: attempt #1 caught exception: 04:39:02 INFO - retry: Failed, sleeping 60 seconds before retrying 04:40:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #2 04:40:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 04:40:05 INFO - Expected file size: 14403485 04:40:05 INFO - Obtained file size: 14403485 04:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:40:05 INFO - trying https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:40:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 04:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 04:40:38 WARNING - URL Error: https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:40:38 INFO - Running command: ['nslookup', 'queue.taskcluster.net'] 04:40:38 INFO - Copy/paste: nslookup queue.taskcluster.net 04:40:38 INFO - Server: 10.26.75.40 04:40:38 INFO - Address: 10.26.75.40#53 04:40:38 INFO - queue.taskcluster.net canonical name = toyama-73636.herokussl.com. 04:40:38 INFO - toyama-73636.herokussl.com canonical name = elb081832-1105536650.us-east-1.elb.amazonaws.com. 04:40:38 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:40:38 INFO - Address: 50.19.247.162 04:40:38 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:40:38 INFO - Address: 50.16.202.48 04:40:38 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 04:40:38 INFO - Address: 50.17.183.103 04:40:38 INFO - Return code: 0 04:40:38 INFO - retry: attempt #1 caught exception: 04:40:38 INFO - retry: Failed, sleeping 30 seconds before retrying 04:41:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #2 04:41:12 INFO - Downloaded 73909797 bytes. 04:41:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:41:12 INFO - mkdir: /builds/slave/test/properties 04:41:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:41:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:41:12 INFO - Contents: 04:41:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:41:14 INFO - Running post-action listener: _resource_record_post_action 04:41:14 INFO - Running post-action listener: set_extra_try_arguments 04:41:14 INFO - [mozharness: 2017-02-21 12:41:14.231993Z] Finished download-and-extract step (success) 04:41:14 INFO - [mozharness: 2017-02-21 12:41:14.232142Z] Running populate-webroot step. 04:41:14 INFO - Running pre-action listener: _resource_record_pre_action 04:41:14 INFO - Running main action method: populate_webroot 04:41:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:41:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:41:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:41:14 INFO - '--tppagecycles', 04:41:14 INFO - '7'], 04:41:14 INFO - 'tests': ['tsvgm']}}, 04:41:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:41:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:41:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:41:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:41:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['damp', 'tps']}, 04:41:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'tests': ['damp', 'tps']}, 04:41:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['dromaeo_dom']}, 04:41:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:41:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['basic_compositor_video']}, 04:41:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:41:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'other-e10s': {'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['a11yr', 04:41:14 INFO - 'ts_paint', 04:41:14 INFO - 'tpaint', 04:41:14 INFO - 'sessionrestore', 04:41:14 INFO - 'sessionrestore_no_auto_restore', 04:41:14 INFO - 'tabpaint']}, 04:41:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['tsvgx', 04:41:14 INFO - 'tsvgr_opacity', 04:41:14 INFO - 'tart', 04:41:14 INFO - 'tscrollx', 04:41:14 INFO - 'cart', 04:41:14 INFO - 'tsvg_static']}, 04:41:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:41:14 INFO - 'tsvgr_opacity', 04:41:14 INFO - 'tart', 04:41:14 INFO - 'tscrollx', 04:41:14 INFO - 'cart', 04:41:14 INFO - 'tsvg_static']}, 04:41:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'talos_options': ['--disable-e10s'], 04:41:14 INFO - 'tests': ['tp5o']}, 04:41:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:41:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'talos_options': ['--disable-e10s', 04:41:14 INFO - '--xperf_path', 04:41:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:14 INFO - 'tests': ['tp5n']}, 04:41:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:41:14 INFO - 'talos_options': ['--xperf_path', 04:41:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:14 INFO - 'tests': ['tp5n']}}, 04:41:14 INFO - 'talos.zip': {'path': '', 04:41:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:41:14 INFO - Running post-action listener: _resource_record_post_action 04:41:14 INFO - [mozharness: 2017-02-21 12:41:14.250885Z] Finished populate-webroot step (success) 04:41:14 INFO - [mozharness: 2017-02-21 12:41:14.251019Z] Running create-virtualenv step. 04:41:14 INFO - Running pre-action listener: _resource_record_pre_action 04:41:14 INFO - Running main action method: create_virtualenv 04:41:14 INFO - Creating virtualenv /builds/slave/test/build/venv 04:41: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 04:41:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:41:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:41:14 INFO - Using real prefix '/tools/python27' 04:41:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:41:15 INFO - Installing distribute.............................................................................................................................................................................................done. 04:41:17 INFO - Installing pip.................done. 04:41:17 INFO - Return code: 0 04:41:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:41:18 INFO - Reading from file tmpfile_stdout 04:41:18 INFO - Output received: 04:41: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) 04:41:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:41: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')]} 04:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41: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')]} 04:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:19 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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:19 INFO - 'HOME': '/Users/cltbld', 04:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:19 INFO - 'LOGNAME': 'cltbld', 04:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:19 INFO - 'MOZ_NO_REMOTE': '1', 04:41:19 INFO - 'NO_EM_RESTART': '1', 04:41:19 INFO - 'PAGER': '/bin/cat', 04:41:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:19 INFO - 'PWD': '/builds/slave/test', 04:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:19 INFO - 'SHELL': '/bin/bash', 04:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:19 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:19 INFO - 'USER': 'cltbld', 04:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:19 INFO - 'XPC_FLAGS': '0x0', 04:41:19 INFO - 'XPC_SERVICE_NAME': '0', 04:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:41:20 INFO - Cleaning up... 04:41:20 INFO - Return code: 0 04:41:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:20 INFO - 'HOME': '/Users/cltbld', 04:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:20 INFO - 'LOGNAME': 'cltbld', 04:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:20 INFO - 'MOZ_NO_REMOTE': '1', 04:41:20 INFO - 'NO_EM_RESTART': '1', 04:41:20 INFO - 'PAGER': '/bin/cat', 04:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:20 INFO - 'PWD': '/builds/slave/test', 04:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:20 INFO - 'SHELL': '/bin/bash', 04:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:20 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:20 INFO - 'USER': 'cltbld', 04:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:20 INFO - 'XPC_FLAGS': '0x0', 04:41:20 INFO - 'XPC_SERVICE_NAME': '0', 04:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:20 INFO - Downloading/unpacking psutil>=3.1.1 04:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:41:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:41:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:41:31 INFO - Installing collected packages: psutil 04:41:31 INFO - Running setup.py install for psutil 04:41:31 INFO - building 'psutil._psutil_osx' extension 04:41:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:41:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:41:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:41:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:41:32 INFO - building 'psutil._psutil_posix' extension 04:41:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:41:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:41:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:41:32 INFO - ^ 04:41:32 INFO - 1 warning generated. 04:41:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:41:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:41:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:41:32 INFO - Successfully installed psutil 04:41:32 INFO - Cleaning up... 04:41:32 INFO - Return code: 0 04:41:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:32 INFO - 'HOME': '/Users/cltbld', 04:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:32 INFO - 'LOGNAME': 'cltbld', 04:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:32 INFO - 'MOZ_NO_REMOTE': '1', 04:41:32 INFO - 'NO_EM_RESTART': '1', 04:41:32 INFO - 'PAGER': '/bin/cat', 04:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:32 INFO - 'PWD': '/builds/slave/test', 04:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:32 INFO - 'SHELL': '/bin/bash', 04:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:32 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:32 INFO - 'USER': 'cltbld', 04:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:32 INFO - 'XPC_FLAGS': '0x0', 04:41:32 INFO - 'XPC_SERVICE_NAME': '0', 04:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:41:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:41:36 INFO - Installing collected packages: mozsystemmonitor 04:41:36 INFO - Running setup.py install for mozsystemmonitor 04:41:36 INFO - Successfully installed mozsystemmonitor 04:41:36 INFO - Cleaning up... 04:41:36 INFO - Return code: 0 04:41:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:41:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:41:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:36 INFO - 'HOME': '/Users/cltbld', 04:41:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:36 INFO - 'LOGNAME': 'cltbld', 04:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:36 INFO - 'MOZ_NO_REMOTE': '1', 04:41:36 INFO - 'NO_EM_RESTART': '1', 04:41:36 INFO - 'PAGER': '/bin/cat', 04:41:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:36 INFO - 'PWD': '/builds/slave/test', 04:41:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:36 INFO - 'SHELL': '/bin/bash', 04:41:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:36 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:36 INFO - 'USER': 'cltbld', 04:41:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:36 INFO - 'XPC_FLAGS': '0x0', 04:41:36 INFO - 'XPC_SERVICE_NAME': '0', 04:41:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:41:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:41:40 INFO - warning: no files found matching '*.txt' 04:41:40 INFO - no previously-included directories found matching 'build' 04:41:40 INFO - no previously-included directories found matching 'dist' 04:41:40 INFO - no previously-included directories found matching '.git*' 04:41:40 INFO - Installing collected packages: jsonschema, functools32 04:41:41 INFO - Running setup.py install for functools32 04:41:41 INFO - warning: no files found matching '*.txt' 04:41:41 INFO - no previously-included directories found matching 'build' 04:41:41 INFO - no previously-included directories found matching 'dist' 04:41:41 INFO - no previously-included directories found matching '.git*' 04:41:41 INFO - Successfully installed jsonschema functools32 04:41:41 INFO - Cleaning up... 04:41:41 INFO - Return code: 0 04:41:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:41 INFO - 'HOME': '/Users/cltbld', 04:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:41 INFO - 'LOGNAME': 'cltbld', 04:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:41 INFO - 'MOZ_NO_REMOTE': '1', 04:41:41 INFO - 'NO_EM_RESTART': '1', 04:41:41 INFO - 'PAGER': '/bin/cat', 04:41:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:41 INFO - 'PWD': '/builds/slave/test', 04:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:41 INFO - 'SHELL': '/bin/bash', 04:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:41 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:41 INFO - 'USER': 'cltbld', 04:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:41 INFO - 'XPC_FLAGS': '0x0', 04:41:41 INFO - 'XPC_SERVICE_NAME': '0', 04:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:41:41 INFO - Cleaning up... 04:41:41 INFO - Return code: 0 04:41:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:41 INFO - 'HOME': '/Users/cltbld', 04:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:41 INFO - 'LOGNAME': 'cltbld', 04:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:41 INFO - 'MOZ_NO_REMOTE': '1', 04:41:41 INFO - 'NO_EM_RESTART': '1', 04:41:41 INFO - 'PAGER': '/bin/cat', 04:41:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:41 INFO - 'PWD': '/builds/slave/test', 04:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:41 INFO - 'SHELL': '/bin/bash', 04:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:41 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:41 INFO - 'USER': 'cltbld', 04:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:41 INFO - 'XPC_FLAGS': '0x0', 04:41:41 INFO - 'XPC_SERVICE_NAME': '0', 04:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:45 INFO - Downloading blobuploader-1.2.4.tar.gz 04:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:41:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:41:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:46 INFO - Downloading docopt-0.6.1.tar.gz 04:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:41:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:41:46 INFO - Running setup.py install for blobuploader 04:41:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:41:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:41:46 INFO - Running setup.py install for requests 04:41:47 INFO - Running setup.py install for docopt 04:41:47 INFO - Successfully installed blobuploader requests docopt 04:41:47 INFO - Cleaning up... 04:41:47 INFO - Return code: 0 04:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41: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')]} 04:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41: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')]} 04:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:47 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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:47 INFO - 'HOME': '/Users/cltbld', 04:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:47 INFO - 'LOGNAME': 'cltbld', 04:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:47 INFO - 'MOZ_NO_REMOTE': '1', 04:41:47 INFO - 'NO_EM_RESTART': '1', 04:41:47 INFO - 'PAGER': '/bin/cat', 04:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:47 INFO - 'PWD': '/builds/slave/test', 04:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:47 INFO - 'SHELL': '/bin/bash', 04:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:47 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:47 INFO - 'USER': 'cltbld', 04:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:47 INFO - 'XPC_FLAGS': '0x0', 04:41:47 INFO - 'XPC_SERVICE_NAME': '0', 04:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:47 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-uvjkBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:47 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-TkcgzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:47 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-KuHShV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:47 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0Pxpvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-q_3PAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-uDR4uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-vY03WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-R4mpPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-fgCQrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-CcYspK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-irMMEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-cjhUQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:48 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-mGPXWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:49 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0VwwWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:49 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-OqNMHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:49 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-67B844-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:49 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-B_nbrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:41:49 INFO - Running setup.py install for manifestparser 04:41:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:41:49 INFO - Running setup.py install for mozcrash 04:41:49 INFO - Running setup.py install for mozdebug 04:41:49 INFO - Running setup.py install for mozdevice 04:41:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Running setup.py install for mozfile 04:41:50 INFO - Running setup.py install for mozhttpd 04:41:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Running setup.py install for mozinfo 04:41:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Running setup.py install for mozInstall 04:41:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Running setup.py install for mozleak 04:41:50 INFO - Running setup.py install for mozlog 04:41:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:41:50 INFO - Running setup.py install for moznetwork 04:41:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:41:51 INFO - Running setup.py install for mozprocess 04:41:51 INFO - Running setup.py install for mozprofile 04:41:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:41:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:41:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:41:51 INFO - Running setup.py install for mozrunner 04:41:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:41:51 INFO - Running setup.py install for mozscreenshot 04:41:51 INFO - Running setup.py install for moztest 04:41:51 INFO - Running setup.py install for mozversion 04:41:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:41:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:41:52 INFO - Cleaning up... 04:41:52 INFO - Return code: 0 04:41:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:52 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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:52 INFO - 'HOME': '/Users/cltbld', 04:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:52 INFO - 'LOGNAME': 'cltbld', 04:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:52 INFO - 'MOZ_NO_REMOTE': '1', 04:41:52 INFO - 'NO_EM_RESTART': '1', 04:41:52 INFO - 'PAGER': '/bin/cat', 04:41:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:52 INFO - 'PWD': '/builds/slave/test', 04:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:52 INFO - 'SHELL': '/bin/bash', 04:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:41:52 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:52 INFO - 'USER': 'cltbld', 04:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:52 INFO - 'XPC_FLAGS': '0x0', 04:41:52 INFO - 'XPC_SERVICE_NAME': '0', 04:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-R_IpM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-rUUhZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-9LaDT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-uoLjPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:52 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)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-e6CuHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0aVSVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:52 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-DUDzRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-R3Yg7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-1bzWTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-fKkrwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-fScYEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-CcH4HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-Ir12Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-PkDfLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-kP_0vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-J_bRNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:53 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-3F7slD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:41:54 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)) 04:41:54 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)) 04:41:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:03 INFO - Downloading blessings-1.6.tar.gz 04:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:42:03 INFO - Installing collected packages: blessings 04:42:03 INFO - Running setup.py install for blessings 04:42:03 INFO - Successfully installed blessings 04:42:03 INFO - Cleaning up... 04:42:03 INFO - Return code: 0 04:42:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:03 INFO - Reading from file tmpfile_stdout 04:42:03 INFO - Current package versions: 04:42:03 INFO - blessings == 1.6 04:42:03 INFO - blobuploader == 1.2.4 04:42:03 INFO - docopt == 0.6.1 04:42:03 INFO - functools32 == 3.2.3-2 04:42:03 INFO - jsonschema == 2.5.1 04:42:03 INFO - manifestparser == 1.1 04:42:03 INFO - mozInstall == 1.12 04:42:03 INFO - mozcrash == 1.0 04:42:03 INFO - mozdebug == 0.1 04:42:03 INFO - mozdevice == 0.49 04:42:03 INFO - mozfile == 1.2 04:42:03 INFO - mozhttpd == 0.7 04:42:03 INFO - mozinfo == 0.9 04:42:03 INFO - mozleak == 0.1 04:42:03 INFO - mozlog == 3.4 04:42:03 INFO - moznetwork == 0.27 04:42:03 INFO - mozprocess == 0.24 04:42:03 INFO - mozprofile == 0.28 04:42:03 INFO - mozrunner == 6.13 04:42:03 INFO - mozscreenshot == 0.1 04:42:03 INFO - mozsystemmonitor == 0.3 04:42:03 INFO - moztest == 0.8 04:42:03 INFO - mozversion == 1.4 04:42:03 INFO - psutil == 3.1.1 04:42:03 INFO - requests == 1.2.3 04:42:03 INFO - wsgiref == 0.1.2 04:42:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:03 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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:03 INFO - 'HOME': '/Users/cltbld', 04:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:03 INFO - 'LOGNAME': 'cltbld', 04:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:03 INFO - 'MOZ_NO_REMOTE': '1', 04:42:03 INFO - 'NO_EM_RESTART': '1', 04:42:03 INFO - 'PAGER': '/bin/cat', 04:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:03 INFO - 'PWD': '/builds/slave/test', 04:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:03 INFO - 'SHELL': '/bin/bash', 04:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:42:03 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:03 INFO - 'USER': 'cltbld', 04:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:03 INFO - 'XPC_FLAGS': '0x0', 04:42:03 INFO - 'XPC_SERVICE_NAME': '0', 04:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:42:04 INFO - Cleaning up... 04:42:04 INFO - Return code: 0 04:42:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:04 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 0x10c4f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe784c7fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, '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.mwgLIsRCnj/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.foGvXkF0oA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:04 INFO - 'HOME': '/Users/cltbld', 04:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:04 INFO - 'LOGNAME': 'cltbld', 04:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:04 INFO - 'MOZ_NO_REMOTE': '1', 04:42:04 INFO - 'NO_EM_RESTART': '1', 04:42:04 INFO - 'PAGER': '/bin/cat', 04:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:04 INFO - 'PWD': '/builds/slave/test', 04:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:04 INFO - 'SHELL': '/bin/bash', 04:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:42:04 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:04 INFO - 'USER': 'cltbld', 04:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:04 INFO - 'XPC_FLAGS': '0x0', 04:42:04 INFO - 'XPC_SERVICE_NAME': '0', 04:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:42:04 INFO - Cleaning up... 04:42:04 INFO - Return code: 0 04:42:04 INFO - Running post-action listener: _resource_record_post_action 04:42:04 INFO - Running post-action listener: _start_resource_monitoring 04:42:04 INFO - Starting resource monitoring. 04:42:04 INFO - [mozharness: 2017-02-21 12:42:04.491219Z] Finished create-virtualenv step (success) 04:42:04 INFO - [mozharness: 2017-02-21 12:42:04.492173Z] Running install step. 04:42:04 INFO - Running pre-action listener: _resource_record_pre_action 04:42:04 INFO - Running main action method: install 04:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:04 INFO - Reading from file tmpfile_stdout 04:42:04 INFO - Detecting whether we're running mozinstall >=1.0... 04:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:42:04 INFO - Reading from file tmpfile_stdout 04:42:04 INFO - Output received: 04:42:04 INFO - Usage: mozinstall [options] installer 04:42:04 INFO - Options: 04:42:04 INFO - -h, --help show this help message and exit 04:42:04 INFO - -d DEST, --destination=DEST 04:42:04 INFO - Directory to install application into. [default: 04:42:04 INFO - "/builds/slave/test"] 04:42:04 INFO - --app=APP Application being installed. [default: firefox] 04:42:04 INFO - mkdir: /builds/slave/test/build/application 04:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:42:25 INFO - Reading from file tmpfile_stdout 04:42:25 INFO - Output received: 04:42:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:42:25 INFO - Running post-action listener: _resource_record_post_action 04:42:25 INFO - [mozharness: 2017-02-21 12:42:25.095444Z] Finished install step (success) 04:42:25 INFO - [mozharness: 2017-02-21 12:42:25.095607Z] Running run-tests step. 04:42:25 INFO - Running pre-action listener: _resource_record_pre_action 04:42:25 INFO - Running main action method: run_tests 04:42:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:42:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:42:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:42:25 INFO - 2017-02-21 04:42:25.110 screenresolution[1628:7813] starting screenresolution argv=screenresolution get 04:42:25 INFO - 2017-02-21 04:42:25.112 screenresolution[1628:7813] Display 0: 1600x1200x32@60 04:42:25 INFO - 2017-02-21 04:42:25.118 screenresolution[1629:7816] starting screenresolution argv=screenresolution list 04:42:25 INFO - Available Modes on Display 0 04:42:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:42:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:42:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:42:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:42:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:42:25 INFO - Intel Iris: 04:42:25 INFO - Chipset Model: Intel Iris 04:42:25 INFO - Type: GPU 04:42:25 INFO - Bus: Built-In 04:42:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:42:25 INFO - Vendor: Intel (0x8086) 04:42:25 INFO - Device ID: 0x0a2e 04:42:25 INFO - Revision ID: 0x0009 04:42:25 INFO - Displays: 04:42:25 INFO - Display: 04:42:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:42:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:42:25 INFO - Display Serial Number: 1600x1200 60 04:42:25 INFO - Main Display: Yes 04:42:25 INFO - Mirror: Off 04:42:25 INFO - Online: Yes 04:42:25 INFO - Rotation: Supported 04:42:25 INFO - Return code: 0 04:42:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:42:25 INFO - Python 2.7.3 04:42:25 INFO - Return code: 0 04:42:25 INFO - grabbing minidump binary from tooltool 04:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:25 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 0x10c2d6d30>, '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 0x10c1fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:42:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:42:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:42:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:42:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:42:25 INFO - Return code: 0 04:42:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:42:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:42:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:42:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:42:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:42:25 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/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:42:25 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/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwgLIsRCnj/Render', 04:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:25 INFO - 'HOME': '/Users/cltbld', 04:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:25 INFO - 'LOGNAME': 'cltbld', 04:42:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:42:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:25 INFO - 'MOZ_NO_REMOTE': '1', 04:42:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:42:25 INFO - 'NO_EM_RESTART': '1', 04:42:25 INFO - 'PAGER': '/bin/cat', 04:42:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:25 INFO - 'PWD': '/builds/slave/test', 04:42:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:25 INFO - 'SHELL': '/bin/bash', 04:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.foGvXkF0oA/Listeners', 04:42:25 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 04:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:25 INFO - 'USER': 'cltbld', 04:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:25 INFO - 'XPC_FLAGS': '0x0', 04:42:25 INFO - 'XPC_SERVICE_NAME': '0', 04:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:25 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/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:42:25 INFO - mozversion application_buildid: 20170221034528 04:42:25 INFO - mozversion application_changeset: cc472627593465885a69a4c1c7e2fd8ad3787c48 04:42:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:42:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:42:25 INFO - mozversion application_name: Firefox 04:42:25 INFO - mozversion application_remotingname: firefox-dev 04:42:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:42:25 INFO - mozversion application_vendor: Mozilla 04:42:25 INFO - mozversion application_version: 53.0a2 04:42:25 INFO - mozversion platform_buildid: 20170221034528 04:42:25 INFO - mozversion platform_changeset: cc472627593465885a69a4c1c7e2fd8ad3787c48 04:42:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:42:25 INFO - mozversion platform_version: 53.0a2 04:42:25 INFO - using testdate: 1487680945 04:42:25 INFO - actual date: 1487680945 04:42:25 INFO - starting webserver on 'localhost:49253' 04:42:25 INFO - SUITE-START | Running 2 tests 04:42:25 INFO - TEST-START | dromaeo_css 04:42:25 INFO - operating with platform_type : mac_ 04:42:25 INFO - Initialising browser for dromaeo_css test... 04:42:25 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpGlcSis/profile http://localhost:49253/getInfo.html) 04:42:28 INFO - PROCESS | 1639 | 2017-02-21 04:42:28.719 plugin-container[1641:7995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 04:42:28 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:42:28 INFO - PROCESS | 1639 | 2017-02-21 04:42:28.722 plugin-container[1641:7995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 04:42:28 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:42:28 INFO - PROCESS | 1639 | 2017-02-21 04:42:28.722 plugin-container[1641:7995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:42:28 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 04:42:28 INFO - PROCESS | 1639 | colorDepth:24 04:42:28 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 04:42:28 INFO - PROCESS | 1639 | __metrics 04:42:28 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:42:29 INFO - TEST-INFO | 1639: exit 0 04:42:29 INFO - Browser initialized. 04:42:29 INFO - Running cycle 1/1 for dromaeo_css test... 04:42:29 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpGlcSis/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:31 INFO - PROCESS | 1643 | 2017-02-21 04:42:31.972 plugin-container[1644:8136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 04:42:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:42:31 INFO - PROCESS | 1643 | 2017-02-21 04:42:31.973 plugin-container[1644:8136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:42:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:42:31 INFO - PROCESS | 1643 | 2017-02-21 04:42:31.973 plugin-container[1644:8136] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:42:41 INFO - PROCESS | 1643 | RSS: Main: 219578368 04:42:41 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49253/tests/dromaeo/cssquery-ext.html) 04:45:46 INFO - PROCESS | 1643 | RSS: Main: 241889280 04:45:46 INFO - PROCESS | 1643 | 04:47:29 INFO - PROCESS | 1643 | ************************* 04:47:29 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 04:47:29 INFO - PROCESS | 1643 | 04:47:29 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:47:29 INFO - PROCESS | 1643 | Full stack: 04:47:29 INFO - PROCESS | 1643 | ************************* 04:48:49 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-ext.html (next: http://localhost:49253/tests/dromaeo/cssquery-jquery.html) 04:48:49 INFO - PROCESS | 1643 | RSS: Main: 249974784 04:48:49 INFO - PROCESS | 1643 | 04:51:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49253/tests/dromaeo/cssquery-mootools.html) 04:51:52 INFO - PROCESS | 1643 | RSS: Main: 256458752 04:51:52 INFO - PROCESS | 1643 | 04:54:54 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49253/tests/dromaeo/cssquery-prototype.html) 04:54:55 INFO - PROCESS | 1643 | RSS: Main: 253136896 04:54:55 INFO - PROCESS | 1643 | 04:57:57 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49253/tests/dromaeo/cssquery-yui.html) 04:57:58 INFO - PROCESS | 1643 | RSS: Main: 257597440 04:57:58 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-yui.html (next: http://localhost:49253/tests/dromaeo/cssquery-dojo.html) 05:01:01 INFO - PROCESS | 1643 | RSS: Main: 258744320 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | __start_tp_report 05:01:01 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 05:01:01 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 05:01:01 INFO - PROCESS | 1643 | |i|pagename|runs| 05:01:01 INFO - PROCESS | 1643 | |0;dojo.html;3367.915802104947;3368.747343949203;3390.4236279832426;3402.7958322500654;3411.31773645271;5841.065429531276;5857.882842343161;5866.178734977107;5870.970645146768;5880.117982302651;5849.561282903788;5921;5939.7921072762465;5948.821535353946;5970.522358211343;4532.97733511332;5242.68543887367;5306.70813105279;5329.840104796843;5369.650972686783;4592.586667199951;5391.353037635489;5396.946030539703;5407.080796264644;5407.188921661763;5149.794008239666;5177.559907407856;5368.892622147555;5374.7043912584795;5384.326959130109;5298.708571028592;5303.337082864623;5348.893022139536;5386.703731294777;5423.647462914898;5194.63637545372;5315.601329900234;5317.042932272189;5338.706371149585;5391.083515802284;5608.327000759935;5914.319853216892;5930.0511918093325;5994.700264986734;6032.728527216286;6146.078088286748;6147.72335244915;6158.753649854;6228.813135605939;6232.220972378453;6255.87488250237;6274.592151510137;6287.151234583318;6287.717052732638;6292.370762923671;5979.701014949235;5982.222311099529;5990.490808281258;6000.249968753906;6009.826635775342;6149;6151.415615516519;6246.094316324107;6272.498200143978;6319.273283572448;6145.525073982212;6157.9384206158265;6184.969075154685;6205.162303089115;6214.502839772807;6094.908576371358;6230.096635987757;6255.937440625536;6260.092286618415;6276.529260305495;4931.630127740362;5705.657660540381;6113.202667687911;6127.220070483952;6156.138140660311;6217.658028808459;6224.128621992924;6236.781712640036;6240.500759939194;6244.500439964792;5000.599952003831;5021.221710634857;5021.774020169101;5043.924341134886;5053.1157047516535;4403.779811009436;4423.668224883147;4429.49060857999;4475.351074094238;4490.438695163105;5935.554833387513;5962.105684147413;6026.126211699367;6033.42682445167;6053.606515576474;6219.626822390671;6235.469985051322;6236.688165591793;6264.342244064308;6267.5299352548745;5915.585908986416;5930.851728706817;5950.642961422242;6031.517478601701;6055.303640081359;5546.389897111315;5549.223108764776;5553.972230138824;5558.722063896789;5593.8321850344555;6439.8068057958335;6441.645709485836;6450.6774661266745;6456.192975878015;6468.741250349934;6139.43080903429;6141.731496609813;6146.323904370605;6182;6268.435840774352;6377.840553986002;6385.521085918667;6386.074019267273;6412.198475190601;6435.32429094957;6122.6938653068055;6165.414285642857;6202.224721909762;6210.347913469021;6259.311475737574;6151.661658608819;6161.9691901542;6205.596636218631;6217.129601855834;6241.937580624136;5052.368453943257;6188.690565471798;6207.782727604693;6209.913265178486;6211.09939058843;5925.623618880798;6201.0388389799655;6201.069839524108;6214.974529202631;6238.594491358047;5934.809962252065;5966.403359663999;6040.486558642388;6073.48375388097;6088.178095956989;6118.497354392036;6130.092410195535;6156.78451254204;6218.471429928507;6235.781747638811;5206.271122042841;6059.333893087028;6108.205933228652;6109.6639684817765;6139.969300153471 05:01:01 INFO - PROCESS | 1643 | |1;ext.html;494.127864319476;501.70650169650776;508.43817581572364;516.4319248826291;546.1970902772928;37496.62503374965;39939.400908986354;39997.4000389994;40188.59811401885;40566;40278;40738.98152546188;40870.18259634806;40910.38634420479;40919.77240682777;8329.750107496784;9088.727338179866;9300.72097837066;9327.30045246606;9348.719538413858;8240.423170378075;11134.721631959184;11388.715282117972;11401.600943966963;11539.884601153964;11028.724281892937;11030.944845275762;11075.612353567627;11088.667339979813;11121.332720036824;11163.497642606102;11249.381284029376;11252.831207531894;11277.37974411407;11302.095832333476;11214.495347709373;11286.322820630789;11298.265612735146;11301.208915375926;11308.09535237179;40057;40162.79918600417;40364.19271614551;40428.393574096415;40494.98762530931;3925.803709814498;3969.206158768258;3988.441618173458;4048.6963477739287;4052.716309858311;29589.70410295891;30632.54051189234;31095.37809243825;31265.531017034536;31390.686093139233;3060.5256185291314;3124.7500199984174;3196.7282780963646;3297.7032067114073;3307.7188438982716;430888;441933;455955;455984;457446.7127664374;59444.10833837496;59686.403135968954;60099;60142.39857601455;60190;62652;62687;62838.685806571586;63046.684766575876;63083;15991.920040399724;16038.358465661477;16050.518484445485;16276.348946042026;16327.510174694778;10878.619248326271;11323.377214253294;11540.192186546861;12295.385230738428;12341.321227332402;15406.614834629225;15729.370825167094;15769.44806931752;15843.445479408165;15853.969491983215;6504.3495650435525;7241.746538871114;7386.224446433154;7546.811329716691;7564.621768911533;767.2480968650642;795.2524626850742;872.8516152254132;874.9387542872065;875.1554749666602;23950.701198484818;29709.40581188364;29937.550936735966;30040.849795751317;30284;949.0651708067548;975.3075316525204;1018.2007124407305;1049.6641074855972;1072.8015317166348;12012.738662440317;13193.340332983313;14008.579742607739;14165.78751318731;14252;591096;593206;595332;596211;600013;48828.267575986385;49001;49029.01941961141;49103;49128.50871491311;1399.3772771116958;1419.141419441244;1451.7894905238893;1452.6876721504927;1453.665656898898;26250.212493625222;26334.73665263323;26447.33881652974;26731.866340668174;27072.32319191957;36421.45367819538;41990.58009419867;44310.33534496921;44601.776991114835;44791.776041119585;501441;501657;504130.4793476009;504228;505891.4705426449;23797;24030.27909162728;24207.152749654382;24319;24401.38996525101;33683;34553.136171595914;51880.48119518907;52566.474335257684;53236.66908327168;33803.83098084494;40077.799611001756;41090.1781964365;42451;42611.7869410651;40096;41953.790231048646;42393.36409953792;42557.36163957605;42735.57264427316;41241.793791030854;41397;42081;42130.36804447997;42505.36241956435;34705.13237169091;35806.82096589605;36764.26471470609;37279;37362.25275494529;22409.32772016842;24425.02299908055;25727;27528.587071193546;27666.585001224594 05:01:01 INFO - PROCESS | 1643 | |2;jquery.html;360.44491483116;385.2852957763351;390.97491326790686;398.6671129606779;425.54891814676444;16212.675746485063;17056.744148837777;19661.31185408511;20877.956102194887;21003.264885729004;20605.690914636256;20705.689414658755;20847.27034553791;20852.270170543998;21040.894795526;5735.111057786029;5929.140274660172;5931.614445061079;5986.790462333819;5998.820035398945;5630.662160270376;5779.624324418899;5827;5925.111233314994;5938.287405511344;5805.477507024367;5809;5812.738426770806;5815.244018277618;5826.417358264182;5625.353084395306;5774.624649397776;5789.131630255453;5827.708614569255;5866.530677545786;4566.429196350457;5166.4575219601775;5198.844034678966;5201.08980928336;5218.9739051304505;19687.704684429744;20182.39452816418;25177.244682659548;25189.49621007588;25516.97932082733;2562.397836508419;2644.867756612181;2714.57924021775;2723.8638068096716;2733.26201925479;9614.71155865325;10231.232657550714;13592.456301747918;15225.923870380688;15847.445339413065;1773.7516747655338;1819.4905426480475;1819.56330480685;1827.4202175075723;1828.2138680367436;123471;128182.35908820489;128422;130027;131549;10280.383177009253;11634.883651163549;12140.696482588006;12148;12184.756304873852;14128.434862605587;14760.335784889705;15988.520344389686;16007.679846403007;16020.439284624983;8439.831203375898;8555.358348123915;8774.122587741174;8828.38201325901;9001.909980900107;8595.18345757152;8602.397832151815;8612.440191387539;8748.912510874938;8754.430961987451;4789.161896668069;4845.9030819383415;4854.441739199967;4857.368542089505;4872.902541949141;2037.9184832606827;2071.2543484345624;2071.8756874587575;2074.668053111495;2083.4166433398673;1005.4168582222294;1007.8007171465932;1020.3316827478013;1024.5440778853338;1056.5984925728173;16729.247183876756;19550.413487595393;20641.793582063987;21532.73470680838;21655;820.6676296100089;1041;1041.6406339812752;1053.2475131655963;1057.8310966382192;11026.84218157098;11157.832632510519;11166.944165279285;11175.44122793857;11207.103431725442;741945;750312;755234;759106;760263;145569;154004;167937;171053;171436;1034.384541198001;1046.0115191144448;1080.2654195147218;1087.983680244797;1090.0407641275785;16484.670306594042;18755.249790008245;20075.196992120153;20459;20525;50438.74780626073;62419.375806241354;65081.37296567624;66671.66664166648;67895.66052169708;97930;98043;98212.50893745486;98666;99893;8356.582170891552;8393.160683931803;8480;8647.956760216159;8651.567421628768;11284.830727539258;12928.806067909178;13086.934565327112;13143.802842957171;13450.327483625975;9572.952135239279;9592.376495527767;11396.544138234378;11479.827802583135;11482;11188.944055279671;11363.034142098015;11411.25826821254;11631;11684.415779210836;10011.048950349705;10154.847677284699;10639.14886809069;11270.380129092979;11297.774044519228;7522.059742532184;7743.574103424366;7830.843383132419;8147.696368581236;8822;8245.670173193244;9571.234301256021;10471.842922356323;11774.646760597196;11876.703082423008 05:01:01 INFO - PROCESS | 1643 | |3;mootools.html;1295.5983645070028;1403.3825116948542;1412.3362019850667;1422.3670466642347;1432.8782053525454;7497.962510187455;7751.418643601738;8025.91974080259;8426.536540490271;8447.23974842264;7152.785416437502;8006.919930800705;8206.220409061145;8300.169983001711;8321.791955201123;2230.576190523799;2250.448640083182;2262.8189744820374;2263.151318255654;2276.7609401012905;1899.420676693608;2076.7611724651633;2234.7430045544806;2284.3832165315357;2291.415688999303;2142.78572142786;2264.049099378265;2273.9886300568473;2279.338991692407;2281.281396360141;2293.220305096267;2297.4830663100834;2311.8612883226892;2315.1549684365086;2327.755585663498;2310.676505289261;2335.556244313592;2349.2482405630203;2355.6348765941307;2363.7636236376397;8323.209295116954;8366.330693544502;8378.622961966665;8390.706325278647;8405.70580029696;5050.823221187277;5188.247704082887;5193.870153246161;5203.687778733288;5215.8174463893965;2923.31302143996;3063.540468929656;3351.4135026370286;3480.269143479872;3528.1356067763563;1095.4686976816238;1179.33367647279;1180.0087926142082;1227.0796902323257;1709.5384246253566;8595.742127736177;10554.577816887315;10850;10859.18556108295;10866.402347870864;4232.830686772495;4496.7077139985795;4505.459344878635;4553.817847286072;4557.612602928788;8507.234348908629;8584.957075214708;8601.913980860236;8602.18279263469;8613.397062205711;5029.949700503022;5069.771860266297;5077.847664570068;5081.542661160439;5088.1350170471105;3726.925461490755;4710.646701497401;4713.269443236304;4759.857204283877;4781.737004464719;3468.8785892493643;3580.230250496104;3752.9249415011545;3803.353429916921;3828.693704503633;1089.3028461784463;1122.9780899381524;1168.3457263932225;1169.5497233565138;1181.5628217559386;688.8622275544911;757.1028331427276;792.1049214387705;822.33390953328;839.0770152831958;4314.374415709704;4960.23116416956;5098.59211263098;5533.280673512418;5594.888102238014;953.9887719017863;997.5012493753123;999.3204620857741;1005.2510879394897;1037.3931250218557;3616;3791.7724936503896;3816.8282427290837;3851.480050193188;3913.158670885716;9336.90663093374;9831.80336393268;9839.60641574329;9876.209903207726;9900.405975641484;9814.165795907285;9817.656382026595;9819.165370943407;9840.557174927002;9851.211903047739;7448.5158464699625;7684.423668224906;7743;7752.806179845662;7767.883481747778;5225.5819534437915;5364.7049412282695;5426.4573542646995;5499.560035197254;5769.538436924952;8985.101489851224;9036.909630903607;9044.819103618023;9086.954565227132;9221.170094691242;9137.314701397288;9314.860277095984;9325.766855828659;9415.435073895589;9433.151016408556;7694.769156925301;7832.334251588679;7842.921570784448;7843.058832940083;7851.293383595504;6478.1578394808375;7696.653650585625;7724.497907635986;7773.455858089879;7821.96089019574;6402.359764023747;7003.62444764419;7094.858102838018;7119.145702515524;7220.963895180491;7003.229644739176;7017.929820701727;7061.82345441368;7135.322144396274;7174.713011479483;6322.778702745566;6350.904736428864;6414.711337989708;6414.93585064162;6430.839229019312;4814.229723244404;4851.927221091616;4858.611311095175;5005.5745261653165;5114.207297868837;4963.925541116959;5013.844047059799;5035.16919708244;5072.4673909239;5161.638685291993 05:01:01 INFO - PROCESS | 1643 | |4;prototype.html;294.82273410860785;344.14651663873576;355.6781113092652;361.6799132767501;364.11520006384484;5324.680519168853;5482.479164479378;5521.944780552193;5572.86067848304;5594.412586678402;4858.295547145662;5354.14333706607;5393.352797664276;5407.324084489439;5508.3114610673665;3327.633960264369;3697.5008373869587;4276.358546218069;4295.763732994699;4296.1097117268455;3759.774413535183;4262.97868510657;4264.360345948102;4321.330193819963;4360.299483201554;3628.87298944537;4251.702380833343;4251.723637963538;4258;4268.679849011337;3725.2549490102087;3781.3382658034734;4236.237477254093;4260.957390426087;4287.2068667296335;3128.8592013359453;3711.5175027246555;3790.374588192945;3793.848246070154;3800.3349413852743;4457.754823484707;5080.745962701887;5315.883664430474;5339.70631615261;5430.402655707869;4667.929981050287;4708.976455117737;4741.976290118562;4770.711907784913;4811.13399588074;5518.91721624172;5732.684702341369;5808.941910580882;5822.700033486749;5867.647941123503;3462.740294477899;3858.3633700439395;3901.2782635212575;3915.5105611798526;3916.255911376858;5342.504098852328;5379.65032272905;5994.833590654497;6052.576319657625;6284.5600807943465;3978.562358140602;4008.0961543266717;4019.678425725935;4020.8793736187963;4024.678025757932;3435.4159792835276;3660.359437098497;3712.2389910068373;3981.6615587675374;3996.2207369562657;4303.763293018851;4311.611954924072;4346.521882592912;4593.47175074864;4604.723716577016;4757.595604373667;5127.769250383742;5836.649801011953;5858.794942177004;5881.647101173858;1220.414201183437;1235.7466719322517;1242.2422322383338;1263.065331654569;1301.4143635364123;803.9638216280281;848.4654667559398;874.7157173918501;893.2987604730256;895.2614093372863;4636.86089417318;4872.466317514873;4890.532840147941;4974.384069187971;5290.444503327172;5747.683877386784;5766.596338256281;5788.421157884262;5800;5871.324797648325;4583.358329833826;4682.190934544999;4967.975160124176;5076.670016448919;5269.235960785742;2721.047633328319;2731.5902614607967;2845.221736414741;2980.3294258792034;3008.9849550752106;5024.748762561857;5029.497050294941;5773.150553534967;5912.260967379078;5958.463738263577;5184.559312458483;5485.698286594195;5718.971405143031;6164.797864416485;6236.345183755731;6043.486303664238;6255.343188965093;6282.30377797992;6298.590591611623;6406.807795766228;5534.944650553604;5900.616459930091;6095.481884039729;6096.268447786294;6097.542684298606;4992.276119962513;5587.972060139673;5851.414858514115;5982.790602328898;5995.88008239824;5358.46415358461;6229.4393504584805;6320.905186422116;6321.652309123042;6330.430261276507;3864.9227015460096;3926.9214615708097;3952.7035472339116;4013.7391069580385;4024.5371782244247;5099.6175286852895;5200.791968321225;6035.215421995113;6053.182820319201;6088.543359248163;5074.847754567369;5537.224788529689;5611.607187496836;5632.295963004624;5717.171010203414;4814.518548145297;4946.851594452172;5737.799177028784;5857.765689372377;5874.412558744091;5462;5662.235598194356;5710.714464276853;5734.111212762028;5738.221151443066;5711.628744131618;5773.971130144322;5794.478496935296;5813.3605303417435;5838.299404071538;4789.473157952552;5710.543156547549;5742.5405967521665;5752.712364381848;5754.510866576388 05:01:01 INFO - PROCESS | 1643 | |5;yui.html;691.3639451704431;697.7522979342331;741.2587412587412;770.1028302028134;808.0021173850294;14340.139591624496;15264.618384540392;15288.54134375969;15305.617359566002;15307.23463826807;14140.646483837909;14178.716425671506;14236.145831250133;14269.143851368899;14288.785668214985;653.2650767886128;832.629479881453;841.6843683618642;843.2579330189425;845.023997283139;674.571647004151;797.7526966640331;846.9576521173917;858.9012263589674;860.6643409070467;902.2962089570145;908.1826356279369;909.1862782809415;910.3218102513604;911.3483859040784;890.9376343655947;891.6700820696384;907.3376435202306;907.4600612635471;908.2506931781273;660.0627109504516;670.5272782688193;671.2984930747381;671.67088126818;671.8152508060274;14546.854531454761;14756.926215368854;14798.038127521677;14828.406863725546;15012.624684382869;14026.43894244229;14299;14346.856531434656;14368.281585920662;14416.423343066264;11562.421878906021;11636.360000199986;11653.70865728355;11729.06167506606;11750.118741094366;3537.434010558324;3600.3879340512176;3626.8005259710703;3656.8902932911788;3761.9059523511855;109552;111121;112919;114944.42527787392;115293.84706152914;8596.957015215008;9544.284178686627;9723.465209413409;11147.554097836159;11377.431128443544;18163.636727265646;21613;22552.436189095075;22556.097756089574;22639;7799.259070388304;7802.121690775338;7834.4907581007055;7899.21007899217;7914.564698941614;4210.957890421118;4267.957320426818;4299.355096735452;4323.827046918088;4336.9591298088235;3531.9293614127573;3562.5724913010604;3574.356615809127;3598.2623562169692;3619.366610843092;548.9176623506426;566.8736915908767;652.3281020548842;663.5521023309246;670.6311528659315;640.9807705768834;641.2721561028267;663.3200969006807;681.8227260912198;701.8175274428685;9214;9999.850002249972;10259.7435064124;10392.740181495523;10455.738606534898;677.4851113154039;688.1673175457752;690.775497963164;700.9264027277164;712.5831388637705;4724.787384567703;4842.927356089662;4911.263310503453;4913.443352679076;4950.356453661001;105672.47163764286;105713;107245;107286;108363.45818270858;71429;75234.4953100935;76811.31160409359;81844.18155818366;83688;74197;76197.6190119057;76224.61887690525;76515;76965.61517192378;10299.897001029894;10830.67507974762;10908.127349812155;10929.617463388744;11022.889771102185;48531.51468485411;49013.509864900894;49969;50584.49415505945;50845.49154508408;71699.28300717135;72800;72978.27021729927;73181.53636927338;75166.62416687881;10483.266171367932;11991.220570663229;12241.326727030098;12860.356982151043;13273.203607783564;13977;14100.435982560994;14211.50259740904;14243.857561424253;15049.322780475124;12932.806007909701;13142.211467311992;13305.80041299362;13309.933450332686;13426.060175787601;12845.155454268099;12908.096433249959;13419.798703019658;13501.257430841399;13874.861251387762;12455.501779928702;13163.341832908125;13167.53913613019;13170.341482926007;13318.600441986757;10374.066334029672;10642.255042146975;10693.625723099654;11040.834387484354;11550.364729939934;9306.348555601313;9689.951550242204;10821.458927053458;10893.78212435731;10942.124630029737 05:01:01 INFO - PROCESS | 1643 | __end_tp_report 05:01:01 INFO - PROCESS | 1643 | __start_cc_report 05:01:01 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,389 05:01:01 INFO - PROCESS | 1643 | __end_cc_report 05:01:01 INFO - PROCESS | 1643 | __startTimestamp1487682061296__endTimestamp 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | ------- Summary: start ------- 05:01:01 INFO - PROCESS | 1643 | Number of tests: 6 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5802.09 Median:6055.30 stddev:643.62 (10.6%) stddev-sans-first:616.61 05:01:01 INFO - PROCESS | 1643 | Values: 3367.9 3368.7 3390.4 3402.8 3411.3 5841.1 5857.9 5866.2 5871.0 5880.1 5849.6 5921.0 5939.8 5948.8 5970.5 4533.0 5242.7 5306.7 5329.8 5369.7 4592.6 5391.4 5396.9 5407.1 5407.2 5149.8 5177.6 5368.9 5374.7 5384.3 5298.7 5303.3 5348.9 5386.7 5423.6 5194.6 5315.6 5317.0 5338.7 5391.1 5608.3 5914.3 5930.1 5994.7 6032.7 6146.1 6147.7 6158.8 6228.8 6232.2 6255.9 6274.6 6287.2 6287.7 6292.4 5979.7 5982.2 5990.5 6000.2 6009.8 6149.0 6151.4 6246.1 6272.5 6319.3 6145.5 6157.9 6185.0 6205.2 6214.5 6094.9 6230.1 6255.9 6260.1 6276.5 4931.6 5705.7 6113.2 6127.2 6156.1 6217.7 6224.1 6236.8 6240.5 6244.5 5000.6 5021.2 5021.8 5043.9 5053.1 4403.8 4423.7 4429.5 4475.4 4490.4 5935.6 5962.1 6026.1 6033.4 6053.6 6219.6 6235.5 6236.7 6264.3 6267.5 5915.6 5930.9 5950.6 6031.5 6055.3 5546.4 5549.2 5554.0 5558.7 5593.8 6439.8 6441.6 6450.7 6456.2 6468.7 6139.4 6141.7 6146.3 6182.0 6268.4 6377.8 6385.5 6386.1 6412.2 6435.3 6122.7 6165.4 6202.2 6210.3 6259.3 6151.7 6162.0 6205.6 6217.1 6241.9 5052.4 6188.7 6207.8 6209.9 6211.1 5925.6 6201.0 6201.1 6215.0 6238.6 5934.8 5966.4 6040.5 6073.5 6088.2 6118.5 6130.1 6156.8 6218.5 6235.8 5206.3 6059.3 6108.2 6109.7 6140.0 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:65331.76 Median:39968.40 stddev:138394.04 (346.3%) stddev-sans-first:138696.66 05:01:01 INFO - PROCESS | 1643 | Values: 494.1 501.7 508.4 516.4 546.2 37496.6 39939.4 39997.4 40188.6 40566.0 40278.0 40739.0 40870.2 40910.4 40919.8 8329.8 9088.7 9300.7 9327.3 9348.7 8240.4 11134.7 11388.7 11401.6 11539.9 11028.7 11030.9 11075.6 11088.7 11121.3 11163.5 11249.4 11252.8 11277.4 11302.1 11214.5 11286.3 11298.3 11301.2 11308.1 40057.0 40162.8 40364.2 40428.4 40495.0 3925.8 3969.2 3988.4 4048.7 4052.7 29589.7 30632.5 31095.4 31265.5 31390.7 3060.5 3124.8 3196.7 3297.7 3307.7 430888.0 441933.0 455955.0 455984.0 457446.7 59444.1 59686.4 60099.0 60142.4 60190.0 62652.0 62687.0 62838.7 63046.7 63083.0 15991.9 16038.4 16050.5 16276.3 16327.5 10878.6 11323.4 11540.2 12295.4 12341.3 15406.6 15729.4 15769.4 15843.4 15854.0 6504.3 7241.7 7386.2 7546.8 7564.6 767.2 795.3 872.9 874.9 875.2 23950.7 29709.4 29937.6 30040.8 30284.0 949.1 975.3 1018.2 1049.7 1072.8 12012.7 13193.3 14008.6 14165.8 14252.0 591096.0 593206.0 595332.0 596211.0 600013.0 48828.3 49001.0 49029.0 49103.0 49128.5 1399.4 1419.1 1451.8 1452.7 1453.7 26250.2 26334.7 26447.3 26731.9 27072.3 36421.5 41990.6 44310.3 44601.8 44791.8 501441.0 501657.0 504130.5 504228.0 505891.5 23797.0 24030.3 24207.2 24319.0 24401.4 33683.0 34553.1 51880.5 52566.5 53236.7 33803.8 40077.8 41090.2 42451.0 42611.8 40096.0 41953.8 42393.4 42557.4 42735.6 41241.8 41397.0 42081.0 42130.4 42505.4 34705.1 35806.8 36764.3 37279.0 37362.3 22409.3 24425.0 25727.0 27528.6 27666.6 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:41423.71 Median:20865.11 stddev:125764.87 (602.8%) stddev-sans-first:126079.88 05:01:01 INFO - PROCESS | 1643 | Values: 360.4 385.3 391.0 398.7 425.5 16212.7 17056.7 19661.3 20878.0 21003.3 20605.7 20705.7 20847.3 20852.3 21040.9 5735.1 5929.1 5931.6 5986.8 5998.8 5630.7 5779.6 5827.0 5925.1 5938.3 5805.5 5809.0 5812.7 5815.2 5826.4 5625.4 5774.6 5789.1 5827.7 5866.5 4566.4 5166.5 5198.8 5201.1 5219.0 19687.7 20182.4 25177.2 25189.5 25517.0 2562.4 2644.9 2714.6 2723.9 2733.3 9614.7 10231.2 13592.5 15225.9 15847.4 1773.8 1819.5 1819.6 1827.4 1828.2 123471.0 128182.4 128422.0 130027.0 131549.0 10280.4 11634.9 12140.7 12148.0 12184.8 14128.4 14760.3 15988.5 16007.7 16020.4 8439.8 8555.4 8774.1 8828.4 9001.9 8595.2 8602.4 8612.4 8748.9 8754.4 4789.2 4845.9 4854.4 4857.4 4872.9 2037.9 2071.3 2071.9 2074.7 2083.4 1005.4 1007.8 1020.3 1024.5 1056.6 16729.2 19550.4 20641.8 21532.7 21655.0 820.7 1041.0 1041.6 1053.2 1057.8 11026.8 11157.8 11166.9 11175.4 11207.1 741945.0 750312.0 755234.0 759106.0 760263.0 145569.0 154004.0 167937.0 171053.0 171436.0 1034.4 1046.0 1080.3 1088.0 1090.0 16484.7 18755.2 20075.2 20459.0 20525.0 50438.7 62419.4 65081.4 66671.7 67895.7 97930.0 98043.0 98212.5 98666.0 99893.0 8356.6 8393.2 8480.0 8648.0 8651.6 11284.8 12928.8 13086.9 13143.8 13450.3 9573.0 9592.4 11396.5 11479.8 11482.0 11188.9 11363.0 11411.3 11631.0 11684.4 10011.0 10154.8 10639.1 11270.4 11297.8 7522.1 7743.6 7830.8 8147.7 8822.0 8245.7 9571.2 10471.8 11774.6 11876.7 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5338.51 Median:5191.06 stddev:2902.59 (55.9%) stddev-sans-first:2894.83 05:01:01 INFO - PROCESS | 1643 | Values: 1295.6 1403.4 1412.3 1422.4 1432.9 7498.0 7751.4 8025.9 8426.5 8447.2 7152.8 8006.9 8206.2 8300.2 8321.8 2230.6 2250.4 2262.8 2263.2 2276.8 1899.4 2076.8 2234.7 2284.4 2291.4 2142.8 2264.0 2274.0 2279.3 2281.3 2293.2 2297.5 2311.9 2315.2 2327.8 2310.7 2335.6 2349.2 2355.6 2363.8 8323.2 8366.3 8378.6 8390.7 8405.7 5050.8 5188.2 5193.9 5203.7 5215.8 2923.3 3063.5 3351.4 3480.3 3528.1 1095.5 1179.3 1180.0 1227.1 1709.5 8595.7 10554.6 10850.0 10859.2 10866.4 4232.8 4496.7 4505.5 4553.8 4557.6 8507.2 8585.0 8601.9 8602.2 8613.4 5029.9 5069.8 5077.8 5081.5 5088.1 3726.9 4710.6 4713.3 4759.9 4781.7 3468.9 3580.2 3752.9 3803.4 3828.7 1089.3 1123.0 1168.3 1169.5 1181.6 688.9 757.1 792.1 822.3 839.1 4314.4 4960.2 5098.6 5533.3 5594.9 954.0 997.5 999.3 1005.3 1037.4 3616.0 3791.8 3816.8 3851.5 3913.2 9336.9 9831.8 9839.6 9876.2 9900.4 9814.2 9817.7 9819.2 9840.6 9851.2 7448.5 7684.4 7743.0 7752.8 7767.9 5225.6 5364.7 5426.5 5499.6 5769.5 8985.1 9036.9 9044.8 9087.0 9221.2 9137.3 9314.9 9325.8 9415.4 9433.2 7694.8 7832.3 7842.9 7843.1 7851.3 6478.2 7696.7 7724.5 7773.5 7822.0 6402.4 7003.6 7094.9 7119.1 7221.0 7003.2 7017.9 7061.8 7135.3 7174.7 6322.8 6350.9 6414.7 6414.9 6430.8 4814.2 4851.9 4858.6 5005.6 5114.2 4963.9 5013.8 5035.2 5072.5 5161.6 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4655.71 Median:5156.16 stddev:1457.55 (28.3%) stddev-sans-first:1424.41 05:01:01 INFO - PROCESS | 1643 | Values: 294.8 344.1 355.7 361.7 364.1 5324.7 5482.5 5521.9 5572.9 5594.4 4858.3 5354.1 5393.4 5407.3 5508.3 3327.6 3697.5 4276.4 4295.8 4296.1 3759.8 4263.0 4264.4 4321.3 4360.3 3628.9 4251.7 4251.7 4258.0 4268.7 3725.3 3781.3 4236.2 4261.0 4287.2 3128.9 3711.5 3790.4 3793.8 3800.3 4457.8 5080.7 5315.9 5339.7 5430.4 4667.9 4709.0 4742.0 4770.7 4811.1 5518.9 5732.7 5808.9 5822.7 5867.6 3462.7 3858.4 3901.3 3915.5 3916.3 5342.5 5379.7 5994.8 6052.6 6284.6 3978.6 4008.1 4019.7 4020.9 4024.7 3435.4 3660.4 3712.2 3981.7 3996.2 4303.8 4311.6 4346.5 4593.5 4604.7 4757.6 5127.8 5836.6 5858.8 5881.6 1220.4 1235.7 1242.2 1263.1 1301.4 804.0 848.5 874.7 893.3 895.3 4636.9 4872.5 4890.5 4974.4 5290.4 5747.7 5766.6 5788.4 5800.0 5871.3 4583.4 4682.2 4968.0 5076.7 5269.2 2721.0 2731.6 2845.2 2980.3 3009.0 5024.7 5029.5 5773.2 5912.3 5958.5 5184.6 5485.7 5719.0 6164.8 6236.3 6043.5 6255.3 6282.3 6298.6 6406.8 5534.9 5900.6 6095.5 6096.3 6097.5 4992.3 5588.0 5851.4 5982.8 5995.9 5358.5 6229.4 6320.9 6321.7 6330.4 3864.9 3926.9 3952.7 4013.7 4024.5 5099.6 5200.8 6035.2 6053.2 6088.5 5074.8 5537.2 5611.6 5632.3 5717.2 4814.5 4946.9 5737.8 5857.8 5874.4 5462.0 5662.2 5710.7 5734.1 5738.2 5711.6 5774.0 5794.5 5813.4 5838.3 4789.5 5710.5 5742.5 5752.7 5754.5 05:01:01 INFO - PROCESS | 1643 | 05:01:01 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20478.06 Median:3641.85 stddev:29777.36 (817.6%) stddev-sans-first:29823.83 05:01:01 INFO - PROCESS | 1643 | Values: 691.4 697.8 741.3 770.1 808.0 14340.1 15264.6 15288.5 15305.6 15307.2 14140.6 14178.7 14236.1 14269.1 14288.8 653.3 832.6 841.7 843.3 845.0 674.6 797.8 847.0 858.9 860.7 902.3 908.2 909.2 910.3 911.3 890.9 891.7 907.3 907.5 908.3 660.1 670.5 671.3 671.7 671.8 14546.9 14756.9 14798.0 14828.4 15012.6 14026.4 14299.0 14346.9 14368.3 14416.4 11562.4 11636.4 11653.7 11729.1 11750.1 3537.4 3600.4 3626.8 3656.9 3761.9 109552.0 111121.0 112919.0 114944.4 115293.8 8597.0 9544.3 9723.5 11147.6 11377.4 18163.6 21613.0 22552.4 22556.1 22639.0 7799.3 7802.1 7834.5 7899.2 7914.6 4211.0 4268.0 4299.4 4323.8 4337.0 3531.9 3562.6 3574.4 3598.3 3619.4 548.9 566.9 652.3 663.6 670.6 641.0 641.3 663.3 681.8 701.8 9214.0 9999.9 10259.7 10392.7 10455.7 677.5 688.2 690.8 700.9 712.6 4724.8 4842.9 4911.3 4913.4 4950.4 105672.5 105713.0 107245.0 107286.0 108363.5 71429.0 75234.5 76811.3 81844.2 83688.0 74197.0 76197.6 76224.6 76515.0 76965.6 10299.9 10830.7 10908.1 10929.6 11022.9 48531.5 49013.5 49969.0 50584.5 50845.5 71699.3 72800.0 72978.3 73181.5 75166.6 10483.3 11991.2 12241.3 12860.4 13273.2 13977.0 14100.4 14211.5 14243.9 15049.3 12932.8 13142.2 13305.8 13309.9 13426.1 12845.2 12908.1 13419.8 13501.3 13874.9 12455.5 13163.3 13167.5 13170.3 13318.6 10374.1 10642.3 10693.6 11040.8 11550.4 9306.3 9690.0 10821.5 10893.8 10942.1 05:01:01 INFO - PROCESS | 1643 | -------- Summary: end -------- 05:01:01 INFO - PROCESS | 1643 | 05:01:02 INFO - TEST-INFO | 1643: exit 0 05:01:02 INFO - TEST-OK | dromaeo_css | took 1116333ms 05:01:02 INFO - TEST-START | kraken 05:01:02 INFO - operating with platform_type : mac_ 05:01:02 INFO - Initialising browser for kraken test... 05:01:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpECJ3jP/profile http://localhost:49253/getInfo.html) 05:01:04 INFO - PROCESS | 1650 | 2017-02-21 05:01:04.633 plugin-container[1651:10490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 05:01:04 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:04 INFO - PROCESS | 1650 | 2017-02-21 05:01:04.636 plugin-container[1651:10490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:01:04 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:04 INFO - PROCESS | 1650 | 2017-02-21 05:01:04.636 plugin-container[1651:10490] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:04 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:01:04 INFO - PROCESS | 1650 | colorDepth:24 05:01:04 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:01:04 INFO - PROCESS | 1650 | __metrics 05:01:04 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:01:05 INFO - TEST-INFO | 1650: exit 0 05:01:05 INFO - Browser initialized. 05:01:05 INFO - Running cycle 1/1 for kraken test... 05:01:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpECJ3jP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:08 INFO - PROCESS | 1652 | 2017-02-21 05:01:08.256 plugin-container[1653:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:01:08 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:08 INFO - PROCESS | 1652 | 2017-02-21 05:01:08.257 plugin-container[1653:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 05:01:08 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:08 INFO - PROCESS | 1652 | 2017-02-21 05:01:08.257 plugin-container[1653:10613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:17 INFO - PROCESS | 1652 | RSS: Main: 219533312 05:01:17 INFO - PROCESS | 1652 | 05:01:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection) 05:01:22 INFO - PROCESS | 1652 | RSS: Main: 234156032 05:01:22 INFO - PROCESS | 1652 | 05:01:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-dft) 05:01:25 INFO - PROCESS | 1652 | RSS: Main: 242499584 05:01:25 INFO - PROCESS | 1652 | 05:01:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-fft) 05:01:29 INFO - PROCESS | 1652 | RSS: Main: 245526528 05:01:29 INFO - PROCESS | 1652 | 05:01:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator) 05:01:31 INFO - PROCESS | 1652 | RSS: Main: 247066624 05:01:31 INFO - PROCESS | 1652 | 05:01:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:01:34 INFO - PROCESS | 1652 | RSS: Main: 246984704 05:01:34 INFO - PROCESS | 1652 | 05:01:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom) 05:01:37 INFO - PROCESS | 1652 | RSS: Main: 251289600 05:01:37 INFO - PROCESS | 1652 | 05:01:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate) 05:01:41 INFO - PROCESS | 1652 | RSS: Main: 249929728 05:01:41 INFO - PROCESS | 1652 | 05:01:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial) 05:01:45 INFO - PROCESS | 1652 | RSS: Main: 250327040 05:01:45 INFO - PROCESS | 1652 | 05:01:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:01:47 INFO - PROCESS | 1652 | RSS: Main: 249581568 05:01:47 INFO - PROCESS | 1652 | 05:01:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:01:50 INFO - PROCESS | 1652 | RSS: Main: 249548800 05:01:50 INFO - PROCESS | 1652 | 05:01:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:01:52 INFO - PROCESS | 1652 | RSS: Main: 250032128 05:01:52 INFO - PROCESS | 1652 | 05:01:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:01:56 INFO - PROCESS | 1652 | RSS: Main: 249962496 05:01:56 INFO - PROCESS | 1652 | 05:01:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:02:00 INFO - PROCESS | 1652 | RSS: Main: 250003456 05:02:00 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49253/tests/kraken/driver.html?testName=ai-astar) 05:02:02 INFO - PROCESS | 1652 | RSS: Main: 251953152 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | __start_tp_report 05:02:02 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:02:02 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:02:02 INFO - PROCESS | 1652 | |i|pagename|runs| 05:02:02 INFO - PROCESS | 1652 | |0;ai-astar;154;167;162;117;119;101;166;168;131;117 05:02:02 INFO - PROCESS | 1652 | |1;audio-beat-detection;144;118;108;175;134;99;140;102;159;138 05:02:02 INFO - PROCESS | 1652 | |2;audio-dft;198;201;198;196;197;198;197;199;195;196 05:02:02 INFO - PROCESS | 1652 | |3;audio-fft;81;59;59;87;61;62;62;60;73;59 05:02:02 INFO - PROCESS | 1652 | |4;audio-oscillator;83;71;84;72;71;71;109;97;96;94 05:02:02 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;92;88;92;99;92;88;93;91;92;88 05:02:02 INFO - PROCESS | 1652 | |6;imaging-darkroom;98;98;99;97;146;126;99;98;98;99 05:02:02 INFO - PROCESS | 1652 | |7;imaging-desaturate;93;94;93;91;95;89;91;119;140;148 05:02:02 INFO - PROCESS | 1652 | |8;json-parse-financial;59;60;67;90;92;89;88;65;63;63 05:02:02 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;68;74;75;79;78;70;62;66;68;70 05:02:02 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;84;83;85;81;85;84;87;87;84;84 05:02:02 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;155;158;164;146;160;166;147;161;207;178 05:02:02 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;170;201;213;218;189;172;169;171;149;206 05:02:02 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;67;65;68;66;67;67;66;65;82 05:02:02 INFO - PROCESS | 1652 | __end_tp_report 05:02:02 INFO - PROCESS | 1652 | __start_cc_report 05:02:02 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,997 05:02:02 INFO - PROCESS | 1652 | __end_cc_report 05:02:02 INFO - PROCESS | 1652 | __startTimestamp1487682122387__endTimestamp 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:02:02 INFO - PROCESS | 1652 | Number of tests: 14 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:140.20 Median:158.00 stddev:25.75 (16.3%) stddev-sans-first:26.83 05:02:02 INFO - PROCESS | 1652 | Values: 154.0 167.0 162.0 117.0 119.0 101.0 166.0 168.0 131.0 117.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:142.00 stddev:24.91 (17.5%) stddev-sans-first:26.02 05:02:02 INFO - PROCESS | 1652 | Values: 144.0 118.0 108.0 175.0 134.0 99.0 140.0 102.0 159.0 138.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:197.50 Median:198.00 stddev:1.72 (0.9%) stddev-sans-first:1.81 05:02:02 INFO - PROCESS | 1652 | Values: 198.0 201.0 198.0 196.0 197.0 198.0 197.0 199.0 195.0 196.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.30 Median:62.00 stddev:10.30 (16.6%) stddev-sans-first:9.45 05:02:02 INFO - PROCESS | 1652 | Values: 81.0 59.0 59.0 87.0 61.0 62.0 62.0 60.0 73.0 59.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:83.50 stddev:13.68 (16.4%) stddev-sans-first:14.49 05:02:02 INFO - PROCESS | 1652 | Values: 83.0 71.0 84.0 72.0 71.0 71.0 109.0 97.0 96.0 94.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:92.00 stddev:3.27 (3.6%) stddev-sans-first:3.47 05:02:02 INFO - PROCESS | 1652 | Values: 92.0 88.0 92.0 99.0 92.0 88.0 93.0 91.0 92.0 88.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:98.00 stddev:16.61 (17.0%) stddev-sans-first:17.38 05:02:02 INFO - PROCESS | 1652 | Values: 98.0 98.0 99.0 97.0 146.0 126.0 99.0 98.0 98.0 99.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:105.30 Median:92.00 stddev:22.18 (24.1%) stddev-sans-first:23.07 05:02:02 INFO - PROCESS | 1652 | Values: 93.0 94.0 93.0 91.0 95.0 89.0 91.0 119.0 140.0 148.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:73.60 Median:77.50 stddev:14.11 (18.2%) stddev-sans-first:13.94 05:02:02 INFO - PROCESS | 1652 | Values: 59.0 60.0 67.0 90.0 92.0 89.0 88.0 65.0 63.0 63.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.00 stddev:5.42 (7.5%) stddev-sans-first:5.63 05:02:02 INFO - PROCESS | 1652 | Values: 68.0 74.0 75.0 79.0 78.0 70.0 62.0 66.0 68.0 70.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.50 stddev:1.78 (2.1%) stddev-sans-first:1.88 05:02:02 INFO - PROCESS | 1652 | Values: 84.0 83.0 85.0 81.0 85.0 84.0 87.0 87.0 84.0 84.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:164.20 Median:162.50 stddev:17.65 (10.9%) stddev-sans-first:18.40 05:02:02 INFO - PROCESS | 1652 | Values: 155.0 158.0 164.0 146.0 160.0 166.0 147.0 161.0 207.0 178.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.80 Median:195.00 stddev:22.90 (11.7%) stddev-sans-first:23.57 05:02:02 INFO - PROCESS | 1652 | Values: 170.0 201.0 213.0 218.0 189.0 172.0 169.0 171.0 149.0 206.0 05:02:02 INFO - PROCESS | 1652 | 05:02:02 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:67.00 stddev:5.01 (7.5%) stddev-sans-first:5.30 05:02:02 INFO - PROCESS | 1652 | Values: 67.0 67.0 65.0 68.0 66.0 67.0 67.0 66.0 65.0 82.0 05:02:02 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:02:02 INFO - PROCESS | 1652 | 05:02:03 INFO - TEST-INFO | 1652: exit 0 05:02:03 INFO - TEST-OK | kraken | took 61388ms 05:02:03 INFO - SUITE-END | took 1177s 05:02:03 INFO - Completed test suite (00:19:37) 05:02:03 INFO - javascript benchmark 05:02:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7313.947624944874, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.915802104947, 3368.747343949203, 3390.4236279832426, 3402.7958322500654, 3411.31773645271, 5841.065429531276, 5857.882842343161, 5866.178734977107, 5870.970645146768, 5880.117982302651, 5849.561282903788, 5921.0, 5939.7921072762465, 5948.821535353946, 5970.522358211343, 4532.97733511332, 5242.68543887367, 5306.70813105279, 5329.840104796843, 5369.650972686783, 4592.586667199951, 5391.353037635489, 5396.946030539703, 5407.080796264644, 5407.188921661763, 5149.794008239666, 5177.559907407856, 5368.892622147555, 5374.7043912584795, 5384.326959130109, 5298.708571028592, 5303.337082864623, 5348.893022139536, 5386.703731294777, 5423.647462914898, 5194.63637545372, 5315.601329900234, 5317.042932272189, 5338.706371149585, 5391.083515802284, 5608.327000759935, 5914.319853216892, 5930.0511918093325, 5994.700264986734, 6032.728527216286, 6146.078088286748, 6147.72335244915, 6158.753649854, 6228.813135605939, 6232.220972378453, 6255.87488250237, 6274.592151510137, 6287.151234583318, 6287.717052732638, 6292.370762923671, 5979.701014949235, 5982.222311099529, 5990.490808281258, 6000.249968753906, 6009.826635775342, 6149.0, 6151.415615516519, 6246.094316324107, 6272.498200143978, 6319.273283572448, 6145.525073982212, 6157.9384206158265, 6184.969075154685, 6205.162303089115, 6214.502839772807, 6094.908576371358, 6230.096635987757, 6255.937440625536, 6260.092286618415, 6276.529260305495, 4931.630127740362, 5705.657660540381, 6113.202667687911, 6127.220070483952, 6156.138140660311, 6217.658028808459, 6224.128621992924, 6236.781712640036, 6240.500759939194, 6244.500439964792, 5000.599952003831, 5021.221710634857, 5021.774020169101, 5043.924341134886, 5053.1157047516535, 4403.779811009436, 4423.668224883147, 4429.49060857999, 4475.351074094238, 4490.438695163105, 5935.554833387513, 5962.105684147413, 6026.126211699367, 6033.42682445167, 6053.606515576474, 6219.626822390671, 6235.469985051322, 6236.688165591793, 6264.342244064308, 6267.5299352548745, 5915.585908986416, 5930.851728706817, 5950.642961422242, 6031.517478601701, 6055.303640081359, 5546.389897111315, 5549.223108764776, 5553.972230138824, 5558.722063896789, 5593.8321850344555, 6439.8068057958335, 6441.645709485836, 6450.6774661266745, 6456.192975878015, 6468.741250349934, 6139.43080903429, 6141.731496609813, 6146.323904370605, 6182.0, 6268.435840774352, 6377.840553986002, 6385.521085918667, 6386.074019267273, 6412.198475190601, 6435.32429094957, 6122.6938653068055, 6165.414285642857, 6202.224721909762, 6210.347913469021, 6259.311475737574, 6151.661658608819, 6161.9691901542, 6205.596636218631, 6217.129601855834, 6241.937580624136, 5052.368453943257, 6188.690565471798, 6207.782727604693, 6209.913265178486, 6211.09939058843, 5925.623618880798, 6201.0388389799655, 6201.069839524108, 6214.974529202631, 6238.594491358047, 5934.809962252065, 5966.403359663999, 6040.486558642388, 6073.48375388097, 6088.178095956989, 6118.497354392036, 6130.092410195535, 6156.78451254204, 6218.471429928507, 6235.781747638811, 5206.271122042841, 6059.333893087028, 6108.205933228652, 6109.6639684817765, 6139.969300153471], "value": 5761.814719000191, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.127864319476, 501.70650169650776, 508.43817581572364, 516.4319248826291, 546.1970902772928, 37496.62503374965, 39939.400908986354, 39997.4000389994, 40188.59811401885, 40566.0, 40278.0, 40738.98152546188, 40870.18259634806, 40910.38634420479, 40919.77240682777, 8329.750107496784, 9088.727338179866, 9300.72097837066, 9327.30045246606, 9348.719538413858, 8240.423170378075, 11134.721631959184, 11388.715282117972, 11401.600943966963, 11539.884601153964, 11028.724281892937, 11030.944845275762, 11075.612353567627, 11088.667339979813, 11121.332720036824, 11163.497642606102, 11249.381284029376, 11252.831207531894, 11277.37974411407, 11302.095832333476, 11214.495347709373, 11286.322820630789, 11298.265612735146, 11301.208915375926, 11308.09535237179, 40057.0, 40162.79918600417, 40364.19271614551, 40428.393574096415, 40494.98762530931, 3925.803709814498, 3969.206158768258, 3988.441618173458, 4048.6963477739287, 4052.716309858311, 29589.70410295891, 30632.54051189234, 31095.37809243825, 31265.531017034536, 31390.686093139233, 3060.5256185291314, 3124.7500199984174, 3196.7282780963646, 3297.7032067114073, 3307.7188438982716, 430888.0, 441933.0, 455955.0, 455984.0, 457446.7127664374, 59444.10833837496, 59686.403135968954, 60099.0, 60142.39857601455, 60190.0, 62652.0, 62687.0, 62838.685806571586, 63046.684766575876, 63083.0, 15991.920040399724, 16038.358465661477, 16050.518484445485, 16276.348946042026, 16327.510174694778, 10878.619248326271, 11323.377214253294, 11540.192186546861, 12295.385230738428, 12341.321227332402, 15406.614834629225, 15729.370825167094, 15769.44806931752, 15843.445479408165, 15853.969491983215, 6504.3495650435525, 7241.746538871114, 7386.224446433154, 7546.811329716691, 7564.621768911533, 767.2480968650642, 795.2524626850742, 872.8516152254132, 874.9387542872065, 875.1554749666602, 23950.701198484818, 29709.40581188364, 29937.550936735966, 30040.849795751317, 30284.0, 949.0651708067548, 975.3075316525204, 1018.2007124407305, 1049.6641074855972, 1072.8015317166348, 12012.738662440317, 13193.340332983313, 14008.579742607739, 14165.78751318731, 14252.0, 591096.0, 593206.0, 595332.0, 596211.0, 600013.0, 48828.267575986385, 49001.0, 49029.01941961141, 49103.0, 49128.50871491311, 1399.3772771116958, 1419.141419441244, 1451.7894905238893, 1452.6876721504927, 1453.665656898898, 26250.212493625222, 26334.73665263323, 26447.33881652974, 26731.866340668174, 27072.32319191957, 36421.45367819538, 41990.58009419867, 44310.33534496921, 44601.776991114835, 44791.776041119585, 501441.0, 501657.0, 504130.4793476009, 504228.0, 505891.4705426449, 23797.0, 24030.27909162728, 24207.152749654382, 24319.0, 24401.38996525101, 33683.0, 34553.136171595914, 51880.48119518907, 52566.474335257684, 53236.66908327168, 33803.83098084494, 40077.799611001756, 41090.1781964365, 42451.0, 42611.7869410651, 40096.0, 41953.790231048646, 42393.36409953792, 42557.36163957605, 42735.57264427316, 41241.793791030854, 41397.0, 42081.0, 42130.36804447997, 42505.36241956435, 34705.13237169091, 35806.82096589605, 36764.26471470609, 37279.0, 37362.25275494529, 22409.32772016842, 24425.02299908055, 25727.0, 27528.587071193546, 27666.585001224594], "value": 19654.71027704956, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.44491483116, 385.2852957763351, 390.97491326790686, 398.6671129606779, 425.54891814676444, 16212.675746485063, 17056.744148837777, 19661.31185408511, 20877.956102194887, 21003.264885729004, 20605.690914636256, 20705.689414658755, 20847.27034553791, 20852.270170543998, 21040.894795526, 5735.111057786029, 5929.140274660172, 5931.614445061079, 5986.790462333819, 5998.820035398945, 5630.662160270376, 5779.624324418899, 5827.0, 5925.111233314994, 5938.287405511344, 5805.477507024367, 5809.0, 5812.738426770806, 5815.244018277618, 5826.417358264182, 5625.353084395306, 5774.624649397776, 5789.131630255453, 5827.708614569255, 5866.530677545786, 4566.429196350457, 5166.4575219601775, 5198.844034678966, 5201.08980928336, 5218.9739051304505, 19687.704684429744, 20182.39452816418, 25177.244682659548, 25189.49621007588, 25516.97932082733, 2562.397836508419, 2644.867756612181, 2714.57924021775, 2723.8638068096716, 2733.26201925479, 9614.71155865325, 10231.232657550714, 13592.456301747918, 15225.923870380688, 15847.445339413065, 1773.7516747655338, 1819.4905426480475, 1819.56330480685, 1827.4202175075723, 1828.2138680367436, 123471.0, 128182.35908820489, 128422.0, 130027.0, 131549.0, 10280.383177009253, 11634.883651163549, 12140.696482588006, 12148.0, 12184.756304873852, 14128.434862605587, 14760.335784889705, 15988.520344389686, 16007.679846403007, 16020.439284624983, 8439.831203375898, 8555.358348123915, 8774.122587741174, 8828.38201325901, 9001.909980900107, 8595.18345757152, 8602.397832151815, 8612.440191387539, 8748.912510874938, 8754.430961987451, 4789.161896668069, 4845.9030819383415, 4854.441739199967, 4857.368542089505, 4872.902541949141, 2037.9184832606827, 2071.2543484345624, 2071.8756874587575, 2074.668053111495, 2083.4166433398673, 1005.4168582222294, 1007.8007171465932, 1020.3316827478013, 1024.5440778853338, 1056.5984925728173, 16729.247183876756, 19550.413487595393, 20641.793582063987, 21532.73470680838, 21655.0, 820.6676296100089, 1041.0, 1041.6406339812752, 1053.2475131655963, 1057.8310966382192, 11026.84218157098, 11157.832632510519, 11166.944165279285, 11175.44122793857, 11207.103431725442, 741945.0, 750312.0, 755234.0, 759106.0, 760263.0, 145569.0, 154004.0, 167937.0, 171053.0, 171436.0, 1034.384541198001, 1046.0115191144448, 1080.2654195147218, 1087.983680244797, 1090.0407641275785, 16484.670306594042, 18755.249790008245, 20075.196992120153, 20459.0, 20525.0, 50438.74780626073, 62419.375806241354, 65081.37296567624, 66671.66664166648, 67895.66052169708, 97930.0, 98043.0, 98212.50893745486, 98666.0, 99893.0, 8356.582170891552, 8393.160683931803, 8480.0, 8647.956760216159, 8651.567421628768, 11284.830727539258, 12928.806067909178, 13086.934565327112, 13143.802842957171, 13450.327483625975, 9572.952135239279, 9592.376495527767, 11396.544138234378, 11479.827802583135, 11482.0, 11188.944055279671, 11363.034142098015, 11411.25826821254, 11631.0, 11684.415779210836, 10011.048950349705, 10154.847677284699, 10639.14886809069, 11270.380129092979, 11297.774044519228, 7522.059742532184, 7743.574103424366, 7830.843383132419, 8147.696368581236, 8822.0, 8245.670173193244, 9571.234301256021, 10471.842922356323, 11774.646760597196, 11876.703082423008], "value": 9990.804382972616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.5983645070028, 1403.3825116948542, 1412.3362019850667, 1422.3670466642347, 1432.8782053525454, 7497.962510187455, 7751.418643601738, 8025.91974080259, 8426.536540490271, 8447.23974842264, 7152.785416437502, 8006.919930800705, 8206.220409061145, 8300.169983001711, 8321.791955201123, 2230.576190523799, 2250.448640083182, 2262.8189744820374, 2263.151318255654, 2276.7609401012905, 1899.420676693608, 2076.7611724651633, 2234.7430045544806, 2284.3832165315357, 2291.415688999303, 2142.78572142786, 2264.049099378265, 2273.9886300568473, 2279.338991692407, 2281.281396360141, 2293.220305096267, 2297.4830663100834, 2311.8612883226892, 2315.1549684365086, 2327.755585663498, 2310.676505289261, 2335.556244313592, 2349.2482405630203, 2355.6348765941307, 2363.7636236376397, 8323.209295116954, 8366.330693544502, 8378.622961966665, 8390.706325278647, 8405.70580029696, 5050.823221187277, 5188.247704082887, 5193.870153246161, 5203.687778733288, 5215.8174463893965, 2923.31302143996, 3063.540468929656, 3351.4135026370286, 3480.269143479872, 3528.1356067763563, 1095.4686976816238, 1179.33367647279, 1180.0087926142082, 1227.0796902323257, 1709.5384246253566, 8595.742127736177, 10554.577816887315, 10850.0, 10859.18556108295, 10866.402347870864, 4232.830686772495, 4496.7077139985795, 4505.459344878635, 4553.817847286072, 4557.612602928788, 8507.234348908629, 8584.957075214708, 8601.913980860236, 8602.18279263469, 8613.397062205711, 5029.949700503022, 5069.771860266297, 5077.847664570068, 5081.542661160439, 5088.1350170471105, 3726.925461490755, 4710.646701497401, 4713.269443236304, 4759.857204283877, 4781.737004464719, 3468.8785892493643, 3580.230250496104, 3752.9249415011545, 3803.353429916921, 3828.693704503633, 1089.3028461784463, 1122.9780899381524, 1168.3457263932225, 1169.5497233565138, 1181.5628217559386, 688.8622275544911, 757.1028331427276, 792.1049214387705, 822.33390953328, 839.0770152831958, 4314.374415709704, 4960.23116416956, 5098.59211263098, 5533.280673512418, 5594.888102238014, 953.9887719017863, 997.5012493753123, 999.3204620857741, 1005.2510879394897, 1037.3931250218557, 3616.0, 3791.7724936503896, 3816.8282427290837, 3851.480050193188, 3913.158670885716, 9336.90663093374, 9831.80336393268, 9839.60641574329, 9876.209903207726, 9900.405975641484, 9814.165795907285, 9817.656382026595, 9819.165370943407, 9840.557174927002, 9851.211903047739, 7448.5158464699625, 7684.423668224906, 7743.0, 7752.806179845662, 7767.883481747778, 5225.5819534437915, 5364.7049412282695, 5426.4573542646995, 5499.560035197254, 5769.538436924952, 8985.101489851224, 9036.909630903607, 9044.819103618023, 9086.954565227132, 9221.170094691242, 9137.314701397288, 9314.860277095984, 9325.766855828659, 9415.435073895589, 9433.151016408556, 7694.769156925301, 7832.334251588679, 7842.921570784448, 7843.058832940083, 7851.293383595504, 6478.1578394808375, 7696.653650585625, 7724.497907635986, 7773.455858089879, 7821.96089019574, 6402.359764023747, 7003.62444764419, 7094.858102838018, 7119.145702515524, 7220.963895180491, 7003.229644739176, 7017.929820701727, 7061.82345441368, 7135.322144396274, 7174.713011479483, 6322.778702745566, 6350.904736428864, 6414.711337989708, 6414.93585064162, 6430.839229019312, 4814.229723244404, 4851.927221091616, 4858.611311095175, 5005.5745261653165, 5114.207297868837, 4963.925541116959, 5013.844047059799, 5035.16919708244, 5072.4673909239, 5161.638685291993], "value": 4337.792360839168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.82273410860785, 344.14651663873576, 355.6781113092652, 361.6799132767501, 364.11520006384484, 5324.680519168853, 5482.479164479378, 5521.944780552193, 5572.86067848304, 5594.412586678402, 4858.295547145662, 5354.14333706607, 5393.352797664276, 5407.324084489439, 5508.3114610673665, 3327.633960264369, 3697.5008373869587, 4276.358546218069, 4295.763732994699, 4296.1097117268455, 3759.774413535183, 4262.97868510657, 4264.360345948102, 4321.330193819963, 4360.299483201554, 3628.87298944537, 4251.702380833343, 4251.723637963538, 4258.0, 4268.679849011337, 3725.2549490102087, 3781.3382658034734, 4236.237477254093, 4260.957390426087, 4287.2068667296335, 3128.8592013359453, 3711.5175027246555, 3790.374588192945, 3793.848246070154, 3800.3349413852743, 4457.754823484707, 5080.745962701887, 5315.883664430474, 5339.70631615261, 5430.402655707869, 4667.929981050287, 4708.976455117737, 4741.976290118562, 4770.711907784913, 4811.13399588074, 5518.91721624172, 5732.684702341369, 5808.941910580882, 5822.700033486749, 5867.647941123503, 3462.740294477899, 3858.3633700439395, 3901.2782635212575, 3915.5105611798526, 3916.255911376858, 5342.504098852328, 5379.65032272905, 5994.833590654497, 6052.576319657625, 6284.5600807943465, 3978.562358140602, 4008.0961543266717, 4019.678425725935, 4020.8793736187963, 4024.678025757932, 3435.4159792835276, 3660.359437098497, 3712.2389910068373, 3981.6615587675374, 3996.2207369562657, 4303.763293018851, 4311.611954924072, 4346.521882592912, 4593.47175074864, 4604.723716577016, 4757.595604373667, 5127.769250383742, 5836.649801011953, 5858.794942177004, 5881.647101173858, 1220.414201183437, 1235.7466719322517, 1242.2422322383338, 1263.065331654569, 1301.4143635364123, 803.9638216280281, 848.4654667559398, 874.7157173918501, 893.2987604730256, 895.2614093372863, 4636.86089417318, 4872.466317514873, 4890.532840147941, 4974.384069187971, 5290.444503327172, 5747.683877386784, 5766.596338256281, 5788.421157884262, 5800.0, 5871.324797648325, 4583.358329833826, 4682.190934544999, 4967.975160124176, 5076.670016448919, 5269.235960785742, 2721.047633328319, 2731.5902614607967, 2845.221736414741, 2980.3294258792034, 3008.9849550752106, 5024.748762561857, 5029.497050294941, 5773.150553534967, 5912.260967379078, 5958.463738263577, 5184.559312458483, 5485.698286594195, 5718.971405143031, 6164.797864416485, 6236.345183755731, 6043.486303664238, 6255.343188965093, 6282.30377797992, 6298.590591611623, 6406.807795766228, 5534.944650553604, 5900.616459930091, 6095.481884039729, 6096.268447786294, 6097.542684298606, 4992.276119962513, 5587.972060139673, 5851.414858514115, 5982.790602328898, 5995.88008239824, 5358.46415358461, 6229.4393504584805, 6320.905186422116, 6321.652309123042, 6330.430261276507, 3864.9227015460096, 3926.9214615708097, 3952.7035472339116, 4013.7391069580385, 4024.5371782244247, 5099.6175286852895, 5200.791968321225, 6035.215421995113, 6053.182820319201, 6088.543359248163, 5074.847754567369, 5537.224788529689, 5611.607187496836, 5632.295963004624, 5717.171010203414, 4814.518548145297, 4946.851594452172, 5737.799177028784, 5857.765689372377, 5874.412558744091, 5462.0, 5662.235598194356, 5710.714464276853, 5734.111212762028, 5738.221151443066, 5711.628744131618, 5773.971130144322, 5794.478496935296, 5813.3605303417435, 5838.299404071538, 4789.473157952552, 5710.543156547549, 5742.5405967521665, 5752.712364381848, 5754.510866576388], "value": 4192.487844888484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.3639451704431, 697.7522979342331, 741.2587412587412, 770.1028302028134, 808.0021173850294, 14340.139591624496, 15264.618384540392, 15288.54134375969, 15305.617359566002, 15307.23463826807, 14140.646483837909, 14178.716425671506, 14236.145831250133, 14269.143851368899, 14288.785668214985, 653.2650767886128, 832.629479881453, 841.6843683618642, 843.2579330189425, 845.023997283139, 674.571647004151, 797.7526966640331, 846.9576521173917, 858.9012263589674, 860.6643409070467, 902.2962089570145, 908.1826356279369, 909.1862782809415, 910.3218102513604, 911.3483859040784, 890.9376343655947, 891.6700820696384, 907.3376435202306, 907.4600612635471, 908.2506931781273, 660.0627109504516, 670.5272782688193, 671.2984930747381, 671.67088126818, 671.8152508060274, 14546.854531454761, 14756.926215368854, 14798.038127521677, 14828.406863725546, 15012.624684382869, 14026.43894244229, 14299.0, 14346.856531434656, 14368.281585920662, 14416.423343066264, 11562.421878906021, 11636.360000199986, 11653.70865728355, 11729.06167506606, 11750.118741094366, 3537.434010558324, 3600.3879340512176, 3626.8005259710703, 3656.8902932911788, 3761.9059523511855, 109552.0, 111121.0, 112919.0, 114944.42527787392, 115293.84706152914, 8596.957015215008, 9544.284178686627, 9723.465209413409, 11147.554097836159, 11377.431128443544, 18163.636727265646, 21613.0, 22552.436189095075, 22556.097756089574, 22639.0, 7799.259070388304, 7802.121690775338, 7834.4907581007055, 7899.21007899217, 7914.564698941614, 4210.957890421118, 4267.957320426818, 4299.355096735452, 4323.827046918088, 4336.9591298088235, 3531.9293614127573, 3562.5724913010604, 3574.356615809127, 3598.2623562169692, 3619.366610843092, 548.9176623506426, 566.8736915908767, 652.3281020548842, 663.5521023309246, 670.6311528659315, 640.9807705768834, 641.2721561028267, 663.3200969006807, 681.8227260912198, 701.8175274428685, 9214.0, 9999.850002249972, 10259.7435064124, 10392.740181495523, 10455.738606534898, 677.4851113154039, 688.1673175457752, 690.775497963164, 700.9264027277164, 712.5831388637705, 4724.787384567703, 4842.927356089662, 4911.263310503453, 4913.443352679076, 4950.356453661001, 105672.47163764286, 105713.0, 107245.0, 107286.0, 108363.45818270858, 71429.0, 75234.4953100935, 76811.31160409359, 81844.18155818366, 83688.0, 74197.0, 76197.6190119057, 76224.61887690525, 76515.0, 76965.61517192378, 10299.897001029894, 10830.67507974762, 10908.127349812155, 10929.617463388744, 11022.889771102185, 48531.51468485411, 49013.509864900894, 49969.0, 50584.49415505945, 50845.49154508408, 71699.28300717135, 72800.0, 72978.27021729927, 73181.53636927338, 75166.62416687881, 10483.266171367932, 11991.220570663229, 12241.326727030098, 12860.356982151043, 13273.203607783564, 13977.0, 14100.435982560994, 14211.50259740904, 14243.857561424253, 15049.322780475124, 12932.806007909701, 13142.211467311992, 13305.80041299362, 13309.933450332686, 13426.060175787601, 12845.155454268099, 12908.096433249959, 13419.798703019658, 13501.257430841399, 13874.861251387762, 12455.501779928702, 13163.341832908125, 13167.53913613019, 13170.341482926007, 13318.600441986757, 10374.066334029672, 10642.255042146975, 10693.625723099654, 11040.834387484354, 11550.364729939934, 9306.348555601313, 9689.951550242204, 10821.458927053458, 10893.78212435731, 10942.124630029737], "value": 7438.705569080109, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 167.0, 162.0, 117.0, 119.0, 101.0, 166.0, 168.0, 131.0, 117.0], "value": 140.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 108.0, 175.0, 134.0, 99.0, 140.0, 102.0, 159.0, 138.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 198.0, 196.0, 197.0, 198.0, 197.0, 199.0, 195.0, 196.0], "value": 197.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 61.0, 62.0, 62.0, 60.0, 73.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 84.0, 72.0, 71.0, 71.0, 109.0, 97.0, 96.0, 94.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 92.0, 99.0, 92.0, 88.0, 93.0, 91.0, 92.0, 88.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 97.0, 146.0, 126.0, 99.0, 98.0, 98.0, 99.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 91.0, 95.0, 89.0, 91.0, 119.0, 140.0, 148.0], "value": 105.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 67.0, 90.0, 92.0, 89.0, 88.0, 65.0, 63.0, 63.0], "value": 73.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 75.0, 79.0, 78.0, 70.0, 62.0, 66.0, 68.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 81.0, 85.0, 84.0, 87.0, 87.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 158.0, 164.0, 146.0, 160.0, 166.0, 147.0, 161.0, 207.0, 178.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 201.0, 213.0, 218.0, 189.0, 172.0, 169.0, 171.0, 149.0, 206.0], "value": 185.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 68.0, 66.0, 67.0, 67.0, 66.0, 65.0, 82.0], "value": 68.0, "unit": "score"}]}]} 05:02:03 INFO - Return code: 0 05:02:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:02:03 ERROR - # TBPL SUCCESS # 05:02:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:03 INFO - 2017-02-21 05:02:03.663 screenresolution[1657:11008] starting screenresolution argv=screenresolution get 05:02:03 INFO - 2017-02-21 05:02:03.667 screenresolution[1657:11008] Display 0: 1600x1200x32@60 05:02:03 INFO - 2017-02-21 05:02:03.674 screenresolution[1658:11011] starting screenresolution argv=screenresolution list 05:02:03 INFO - Available Modes on Display 0 05:02:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:03 INFO - Intel Iris: 05:02:03 INFO - Chipset Model: Intel Iris 05:02:03 INFO - Type: GPU 05:02:03 INFO - Bus: Built-In 05:02:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:03 INFO - Vendor: Intel (0x8086) 05:02:03 INFO - Device ID: 0x0a2e 05:02:03 INFO - Revision ID: 0x0009 05:02:03 INFO - Displays: 05:02:03 INFO - Display: 05:02:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:03 INFO - Display Serial Number: 1600x1200 60 05:02:03 INFO - Main Display: Yes 05:02:03 INFO - Mirror: Off 05:02:03 INFO - Online: Yes 05:02:03 INFO - Rotation: Supported 05:02:03 INFO - Return code: 0 05:02:03 INFO - Running post-action listener: _resource_record_post_action 05:02:03 INFO - [mozharness: 2017-02-21 13:02:03.919191Z] Finished run-tests step (success) 05:02:03 INFO - Running post-run listener: _resource_record_post_run 05:02:03 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 34251264; Write bytes: 514348544; Read time: 322; Write time: 1574 05:02:03 INFO - TinderboxPrint: CPU usage
54.2% 05:02:03 INFO - TinderboxPrint: I/O read bytes / time
34,251,264 / 322 05:02:03 INFO - TinderboxPrint: I/O write bytes / time
514,348,544 / 1,574 05:02:03 INFO - TinderboxPrint: CPU idle
2,197.9 (45.8%) 05:02:03 INFO - TinderboxPrint: CPU system
957.7 (20.0%) 05:02:03 INFO - TinderboxPrint: CPU user
1,642.2 (34.2%) 05:02:03 INFO - TinderboxPrint: Swap in / out
237,903,872 / 0 05:02:03 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201435648; Write bytes: 200546304; Read time: 15588; Write time: 410 05:02:03 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32416256; Write bytes: 303111680; Read time: 297; Write time: 1129 05:02:04 INFO - Running post-run listener: _upload_blobber_files 05:02:04 INFO - Blob upload gear active. 05:02:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:02:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:02:04 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'] 05:02:04 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 05:02:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:06 INFO - (blobuploader) - INFO - Done attempting. 05:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:02:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:07 INFO - (blobuploader) - INFO - Done attempting. 05:02:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:02:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:02:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:08 INFO - (blobuploader) - INFO - Done attempting. 05:02:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:02:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:02:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:10 INFO - (blobuploader) - INFO - Done attempting. 05:02:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:02:10 INFO - Return code: 0 05:02:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:02:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:02:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b71cbaf708093792232a34f19578db26f31c6fa920a3e92db4c3511daa9cd683bf45c3006b84f5884fa52fd15914613bf81b35d9a6670ae90c46be5bd95103c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ebde0687abdeac7699b5374d1dc73fcfbbd159de6e45d6a6d085f7aec1aa4e8604d77b20845800b5c51ebaf5c0e8e007755f40d5fbd5f936624774def93b4fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d285128639f8875f58e0d02eae0b9f922d9db575cc4608093275d55a656ae4dfac7593f954364245ad863b6eb0d4e01fcd32708b18f09ef9d56a53536505bd50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfcd067bae0464c4be2ac903af0e5ceef6dad3a5346b01e7f3c2aa0d4da4a34bfcbc02158e232d6bf130900221264bfc68ca3998e6e11d430cbfe43cdd40cf2"} 05:02:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:02:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:02:10 INFO - Contents: 05:02:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b71cbaf708093792232a34f19578db26f31c6fa920a3e92db4c3511daa9cd683bf45c3006b84f5884fa52fd15914613bf81b35d9a6670ae90c46be5bd95103c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ebde0687abdeac7699b5374d1dc73fcfbbd159de6e45d6a6d085f7aec1aa4e8604d77b20845800b5c51ebaf5c0e8e007755f40d5fbd5f936624774def93b4fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d285128639f8875f58e0d02eae0b9f922d9db575cc4608093275d55a656ae4dfac7593f954364245ad863b6eb0d4e01fcd32708b18f09ef9d56a53536505bd50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfcd067bae0464c4be2ac903af0e5ceef6dad3a5346b01e7f3c2aa0d4da4a34bfcbc02158e232d6bf130900221264bfc68ca3998e6e11d430cbfe43cdd40cf2"} 05:02:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:02:10 INFO - Copying logs to upload dir... 05:02:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:02:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1449.388664 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 24 mins, 9 secs) (at 2017-02-21 05:02:10.699115) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 05:02:10.702533) ========= 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.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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/1b71cbaf708093792232a34f19578db26f31c6fa920a3e92db4c3511daa9cd683bf45c3006b84f5884fa52fd15914613bf81b35d9a6670ae90c46be5bd95103c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ebde0687abdeac7699b5374d1dc73fcfbbd159de6e45d6a6d085f7aec1aa4e8604d77b20845800b5c51ebaf5c0e8e007755f40d5fbd5f936624774def93b4fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d285128639f8875f58e0d02eae0b9f922d9db575cc4608093275d55a656ae4dfac7593f954364245ad863b6eb0d4e01fcd32708b18f09ef9d56a53536505bd50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfcd067bae0464c4be2ac903af0e5ceef6dad3a5346b01e7f3c2aa0d4da4a34bfcbc02158e232d6bf130900221264bfc68ca3998e6e11d430cbfe43cdd40cf2"} build_url:https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011167 build_url: 'https://queue.taskcluster.net/v1/task/Lhouh6awRzSJybTT9uqIPw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b71cbaf708093792232a34f19578db26f31c6fa920a3e92db4c3511daa9cd683bf45c3006b84f5884fa52fd15914613bf81b35d9a6670ae90c46be5bd95103c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ebde0687abdeac7699b5374d1dc73fcfbbd159de6e45d6a6d085f7aec1aa4e8604d77b20845800b5c51ebaf5c0e8e007755f40d5fbd5f936624774def93b4fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d285128639f8875f58e0d02eae0b9f922d9db575cc4608093275d55a656ae4dfac7593f954364245ad863b6eb0d4e01fcd32708b18f09ef9d56a53536505bd50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfcd067bae0464c4be2ac903af0e5ceef6dad3a5346b01e7f3c2aa0d4da4a34bfcbc02158e232d6bf130900221264bfc68ca3998e6e11d430cbfe43cdd40cf2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 05:02:10.742072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:02:10.742417) ========= 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.mwgLIsRCnj/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.foGvXkF0oA/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.005054 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:02:10.793077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 05:02:10.793727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 05:02:10.794302) ========= ========= Total master_lag: 1.92 =========