builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0003 starttime: 1496765997.19 results: success (0) buildid: 20170606081521 builduid: ed94bfc3ba194992a050c833444230e4 revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.195098) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.195984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.196687) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.222969) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.223640) ========= 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.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-06-06 09:19:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 176M=0s 2017-06-06 09:19:57 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149023 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.401887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.402798) ========= 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.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.028360 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:57.457807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:19:57.458742) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fe438f1ec216c0269d353df141ea376055f6130c --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-beta --rev fe438f1ec216c0269d353df141ea376055f6130c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-06-06 09:19:57,559 truncating revision to first 12 chars 2017-06-06 09:19:57,559 Setting DEBUG logging. 2017-06-06 09:19:57,559 attempt 1/10 2017-06-06 09:19:57,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fe438f1ec216?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 09:19:58,388 unpacking tar archive at: mozilla-beta-fe438f1ec216/testing/mozharness/ program finished with exit code 0 elapsedTime=1.113969 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:19:58.598782) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:58.599611) ========= script_repo_revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:58.600413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:58.600988) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:19:58.621536) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-06 09:19:58.621981) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:19:58 INFO - MultiFileLogger online at 20170606 09:19:58 in /builds/slave/test 09:19:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:19:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:58 INFO - {'append_to_log': False, 09:19:58 INFO - 'base_work_dir': '/builds/slave/test', 09:19:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:19:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:58 INFO - 'branch': 'Mozilla-Beta', 09:19:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:58 INFO - 'config_files': ('talos/mac_config.py',), 09:19:58 INFO - 'default_actions': ('clobber', 09:19:58 INFO - 'read-buildbot-config', 09:19:58 INFO - 'download-and-extract', 09:19:58 INFO - 'populate-webroot', 09:19:58 INFO - 'create-virtualenv', 09:19:58 INFO - 'install', 09:19:58 INFO - 'run-tests'), 09:19:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:58 INFO - 'download_minidump_stackwalk': True, 09:19:58 INFO - 'download_symbols': 'ondemand', 09:19:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:58 INFO - 'gecko_profile': False, 09:19:58 INFO - 'gecko_profile_interval': 0, 09:19:58 INFO - 'installer_path': 'installer.exe', 09:19:58 INFO - 'log_level': 'info', 09:19:58 INFO - 'log_name': 'talos', 09:19:58 INFO - 'log_to_console': True, 09:19:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:19:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:19:58 INFO - 'opt_config_files': (), 09:19:58 INFO - 'pip_index': False, 09:19:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:58 INFO - 'cmd': ('bash', 09:19:58 INFO - '-c', 09:19:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:58 INFO - 'enabled': True, 09:19:58 INFO - 'halt_on_failure': False, 09:19:58 INFO - 'name': 'check_screen_resolution'},), 09:19:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:58 INFO - 'cmd': ('bash', 09:19:58 INFO - '-c', 09:19:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:58 INFO - 'enabled': True, 09:19:58 INFO - 'halt_on_failure': False, 09:19:58 INFO - 'name': 'check_screen_resolution'},), 09:19:58 INFO - 'run_cmd_checks_enabled': True, 09:19:58 INFO - 'suite': 'dromaeojs-e10s', 09:19:58 INFO - 'system_bits': '32', 09:19:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:58 INFO - 'title': 't-yosemite-r7-0003', 09:19:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:58 INFO - 'use_talos_json': True, 09:19:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:58 INFO - 'work_dir': 'build'} 09:19:58 INFO - [mozharness: 2017-06-06 16:19:58.828291Z] Running clobber step. 09:19:58 INFO - Running pre-action listener: _resource_record_pre_action 09:19:58 INFO - Running main action method: clobber 09:19:58 INFO - rmtree: /builds/slave/test/build 09:19:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:01 INFO - Running post-action listener: _resource_record_post_action 09:20:01 INFO - [mozharness: 2017-06-06 16:20:01.346389Z] Finished clobber step (success) 09:20:01 INFO - [mozharness: 2017-06-06 16:20:01.346628Z] Running read-buildbot-config step. 09:20:01 INFO - Running pre-action listener: _resource_record_pre_action 09:20:01 INFO - Running main action method: read_buildbot_config 09:20:01 INFO - Using buildbot properties: 09:20:01 INFO - { 09:20:01 INFO - "project": "", 09:20:01 INFO - "product": "firefox", 09:20:01 INFO - "script_repo_revision": "production", 09:20:01 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 09:20:01 INFO - "repository": "", 09:20:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 09:20:01 INFO - "buildid": "20170606081521", 09:20:01 INFO - "pgo_build": "False", 09:20:01 INFO - "basedir": "/builds/slave/test", 09:20:01 INFO - "buildnumber": 4, 09:20:01 INFO - "slavename": "t-yosemite-r7-0003", 09:20:01 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:20:01 INFO - "platform": "yosemite_r7_devedition", 09:20:01 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 09:20:01 INFO - "branch": "mozilla-beta", 09:20:01 INFO - "repo_path": "releases/mozilla-beta", 09:20:01 INFO - "revision": "fe438f1ec216c0269d353df141ea376055f6130c", 09:20:01 INFO - "stage_platform": "macosx64-devedition", 09:20:01 INFO - "builduid": "ed94bfc3ba194992a050c833444230e4", 09:20:01 INFO - "slavebuilddir": "test" 09:20:01 INFO - } 09:20:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:20:01 INFO - Running post-action listener: _resource_record_post_action 09:20:01 INFO - [mozharness: 2017-06-06 16:20:01.352329Z] Finished read-buildbot-config step (success) 09:20:01 INFO - [mozharness: 2017-06-06 16:20:01.352546Z] Running download-and-extract step. 09:20:01 INFO - Running pre-action listener: _resource_record_pre_action 09:20:01 INFO - Running main action method: download_and_extract 09:20:01 INFO - mkdir: /builds/slave/test/build/tests 09:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:01 INFO - trying https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:20:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:20:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:20:02 INFO - Downloaded 1287 bytes. 09:20:02 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:20:02 INFO - Using the following test package requirements: 09:20:02 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:20:02 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:20:02 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:20:02 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:20:02 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:20:02 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:20:02 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:20:02 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:20:02 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:20:02 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:20:02 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:20:02 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:20:02 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:20:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:20:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:20:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:20:03 INFO - Expected file size: 51963910 09:20:03 INFO - Obtained file size: 51963910 09:20:19 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:20:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:20:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:20:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:20:21 INFO - Expected file size: 51963910 09:20:21 INFO - Obtained file size: 51963910 09:20:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:20:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:20:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:20:38 INFO - Expected file size: 14404461 09:20:38 INFO - Obtained file size: 14404461 09:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:38 INFO - trying https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:20:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:20:41 INFO - Downloaded 74884596 bytes. 09:20:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:20:41 INFO - mkdir: /builds/slave/test/properties 09:20:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:20:41 INFO - Writing to file /builds/slave/test/properties/build_url 09:20:41 INFO - Contents: 09:20:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:20:41 INFO - Running post-action listener: _resource_record_post_action 09:20:41 INFO - Running post-action listener: set_extra_try_arguments 09:20:41 INFO - [mozharness: 2017-06-06 16:20:41.809220Z] Finished download-and-extract step (success) 09:20:41 INFO - [mozharness: 2017-06-06 16:20:41.809375Z] Running populate-webroot step. 09:20:41 INFO - Running pre-action listener: _resource_record_pre_action 09:20:41 INFO - Running main action method: populate_webroot 09:20:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:20:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:20:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:20:41 INFO - '--tppagecycles', 09:20:41 INFO - '7'], 09:20:41 INFO - 'tests': ['tsvgm']}}, 09:20:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:20:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:20:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:20:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:20:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['damp', 'tps']}, 09:20:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'tests': ['damp', 'tps']}, 09:20:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['dromaeo_dom']}, 09:20:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:20:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['basic_compositor_video']}, 09:20:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:20:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['a11yr', 09:20:41 INFO - 'ts_paint', 09:20:41 INFO - 'tpaint', 09:20:41 INFO - 'sessionrestore', 09:20:41 INFO - 'sessionrestore_no_auto_restore', 09:20:41 INFO - 'tabpaint']}, 09:20:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['tsvgx', 09:20:41 INFO - 'tsvgr_opacity', 09:20:41 INFO - 'tart', 09:20:41 INFO - 'tscrollx', 09:20:41 INFO - 'cart', 09:20:41 INFO - 'tsvg_static']}, 09:20:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:20:41 INFO - 'tsvgr_opacity', 09:20:41 INFO - 'tart', 09:20:41 INFO - 'tscrollx', 09:20:41 INFO - 'cart', 09:20:41 INFO - 'tsvg_static']}, 09:20:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'talos_options': ['--disable-e10s'], 09:20:41 INFO - 'tests': ['tp5o']}, 09:20:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:20:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'talos_options': ['--disable-e10s', 09:20:41 INFO - '--xperf_path', 09:20:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:41 INFO - 'tests': ['tp5n']}, 09:20:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:20:41 INFO - 'talos_options': ['--xperf_path', 09:20:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:41 INFO - 'tests': ['tp5n']}}, 09:20:41 INFO - 'talos.zip': {'path': '', 09:20:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:20:41 INFO - Running post-action listener: _resource_record_post_action 09:20:41 INFO - [mozharness: 2017-06-06 16:20:41.828578Z] Finished populate-webroot step (success) 09:20:41 INFO - [mozharness: 2017-06-06 16:20:41.828727Z] Running create-virtualenv step. 09:20:41 INFO - Running pre-action listener: _resource_record_pre_action 09:20:41 INFO - Running main action method: create_virtualenv 09:20:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:20:41 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 09:20:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:20:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:20:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:20:41 INFO - Using real prefix '/tools/python27' 09:20:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:20:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:45 INFO - Installing pip.................done. 09:20:45 INFO - Return code: 0 09:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:20:45 INFO - Reading from file tmpfile_stdout 09:20:45 INFO - Output received: 09:20:45 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) 09:20:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:45 INFO - 'HOME': '/Users/cltbld', 09:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:45 INFO - 'LOGNAME': 'cltbld', 09:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:45 INFO - 'MOZ_NO_REMOTE': '1', 09:20:45 INFO - 'NO_EM_RESTART': '1', 09:20:45 INFO - 'PAGER': '/bin/cat', 09:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:45 INFO - 'PWD': '/builds/slave/test', 09:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:45 INFO - 'SHELL': '/bin/bash', 09:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:45 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:45 INFO - 'USER': 'cltbld', 09:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:45 INFO - 'XPC_FLAGS': '0x0', 09:20:45 INFO - 'XPC_SERVICE_NAME': '0', 09:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:46 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 09:20:46 INFO - Cleaning up... 09:20:46 INFO - Return code: 0 09:20:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:20:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:46 INFO - 'HOME': '/Users/cltbld', 09:20:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:46 INFO - 'LOGNAME': 'cltbld', 09:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:46 INFO - 'MOZ_NO_REMOTE': '1', 09:20:46 INFO - 'NO_EM_RESTART': '1', 09:20:46 INFO - 'PAGER': '/bin/cat', 09:20:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:46 INFO - 'PWD': '/builds/slave/test', 09:20:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:46 INFO - 'SHELL': '/bin/bash', 09:20:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:46 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:46 INFO - 'USER': 'cltbld', 09:20:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:46 INFO - 'XPC_FLAGS': '0x0', 09:20:46 INFO - 'XPC_SERVICE_NAME': '0', 09:20:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:46 INFO - Downloading/unpacking psutil>=3.1.1 09:20:46 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 09:20:46 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 09:20:46 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 09:20:46 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 09:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:48 INFO - Installing collected packages: psutil 09:20:48 INFO - Running setup.py install for psutil 09:20:48 INFO - building 'psutil._psutil_osx' extension 09:20:48 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 09:20:48 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 09:20:49 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 09:20:49 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 09:20:49 INFO - building 'psutil._psutil_posix' extension 09:20:49 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 09:20:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:20:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:20:49 INFO - ^ 09:20:49 INFO - 1 warning generated. 09:20:49 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 09:20:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:49 INFO - Successfully installed psutil 09:20:49 INFO - Cleaning up... 09:20:49 INFO - Return code: 0 09:20:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:49 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/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:20:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:49 INFO - 'HOME': '/Users/cltbld', 09:20:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:49 INFO - 'LOGNAME': 'cltbld', 09:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:49 INFO - 'MOZ_NO_REMOTE': '1', 09:20:49 INFO - 'NO_EM_RESTART': '1', 09:20:49 INFO - 'PAGER': '/bin/cat', 09:20:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:49 INFO - 'PWD': '/builds/slave/test', 09:20:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:49 INFO - 'SHELL': '/bin/bash', 09:20:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:49 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:49 INFO - 'USER': 'cltbld', 09:20:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:49 INFO - 'XPC_FLAGS': '0x0', 09:20:49 INFO - 'XPC_SERVICE_NAME': '0', 09:20:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:20:49 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 09:20:49 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 09:20:49 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 09:20:49 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 09:20:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:20:51 INFO - Installing collected packages: mozsystemmonitor 09:20:51 INFO - Running setup.py install for mozsystemmonitor 09:20:51 INFO - Successfully installed mozsystemmonitor 09:20:51 INFO - Cleaning up... 09:20:51 INFO - Return code: 0 09:20:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:51 INFO - 'HOME': '/Users/cltbld', 09:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:51 INFO - 'LOGNAME': 'cltbld', 09:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:51 INFO - 'MOZ_NO_REMOTE': '1', 09:20:51 INFO - 'NO_EM_RESTART': '1', 09:20:51 INFO - 'PAGER': '/bin/cat', 09:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:51 INFO - 'PWD': '/builds/slave/test', 09:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:51 INFO - 'SHELL': '/bin/bash', 09:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:51 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:51 INFO - 'USER': 'cltbld', 09:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:51 INFO - 'XPC_FLAGS': '0x0', 09:20:51 INFO - 'XPC_SERVICE_NAME': '0', 09:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:20:51 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 09:20:51 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 09:20:51 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 09:20:51 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 09:20:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:53 INFO - warning: no files found matching '*.txt' 09:20:53 INFO - no previously-included directories found matching 'build' 09:20:53 INFO - no previously-included directories found matching 'dist' 09:20:53 INFO - no previously-included directories found matching '.git*' 09:20:53 INFO - Installing collected packages: jsonschema, functools32 09:20:53 INFO - Running setup.py install for functools32 09:20:53 INFO - warning: no files found matching '*.txt' 09:20:53 INFO - no previously-included directories found matching 'build' 09:20:53 INFO - no previously-included directories found matching 'dist' 09:20:53 INFO - no previously-included directories found matching '.git*' 09:20:53 INFO - Successfully installed jsonschema functools32 09:20:53 INFO - Cleaning up... 09:20:53 INFO - Return code: 0 09:20:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:54 INFO - 'HOME': '/Users/cltbld', 09:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:54 INFO - 'LOGNAME': 'cltbld', 09:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:54 INFO - 'MOZ_NO_REMOTE': '1', 09:20:54 INFO - 'NO_EM_RESTART': '1', 09:20:54 INFO - 'PAGER': '/bin/cat', 09:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:54 INFO - 'PWD': '/builds/slave/test', 09:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:54 INFO - 'SHELL': '/bin/bash', 09:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:54 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:54 INFO - 'USER': 'cltbld', 09:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:54 INFO - 'XPC_FLAGS': '0x0', 09:20:54 INFO - 'XPC_SERVICE_NAME': '0', 09:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:20:54 INFO - Cleaning up... 09:20:54 INFO - Return code: 0 09:20:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:55 INFO - 'HOME': '/Users/cltbld', 09:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:55 INFO - 'LOGNAME': 'cltbld', 09:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:55 INFO - 'MOZ_NO_REMOTE': '1', 09:20:55 INFO - 'NO_EM_RESTART': '1', 09:20:55 INFO - 'PAGER': '/bin/cat', 09:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:55 INFO - 'PWD': '/builds/slave/test', 09:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:55 INFO - 'SHELL': '/bin/bash', 09:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:55 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:55 INFO - 'USER': 'cltbld', 09:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:55 INFO - 'XPC_FLAGS': '0x0', 09:20:55 INFO - 'XPC_SERVICE_NAME': '0', 09:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:57 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 09:20:57 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 09:20:57 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 09:20:57 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 09:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:57 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 09:20:57 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 09:20:57 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 09:20:57 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 09:20:58 INFO - Downloading docopt-0.6.1.tar.gz 09:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:58 INFO - Running setup.py install for blobuploader 09:20:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:58 INFO - Running setup.py install for requests 09:20:58 INFO - Running setup.py install for docopt 09:20:59 INFO - Successfully installed blobuploader requests docopt 09:20:59 INFO - Cleaning up... 09:20:59 INFO - Return code: 0 09:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:59 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/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:20:59 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:59 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:59 INFO - 'HOME': '/Users/cltbld', 09:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:59 INFO - 'LOGNAME': 'cltbld', 09:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:59 INFO - 'MOZ_NO_REMOTE': '1', 09:20:59 INFO - 'NO_EM_RESTART': '1', 09:20:59 INFO - 'PAGER': '/bin/cat', 09:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:59 INFO - 'PWD': '/builds/slave/test', 09:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:59 INFO - 'SHELL': '/bin/bash', 09:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:20:59 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:59 INFO - 'USER': 'cltbld', 09:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:59 INFO - 'XPC_FLAGS': '0x0', 09:20:59 INFO - 'XPC_SERVICE_NAME': '0', 09:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-gjBDeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-0_mB8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-qXoW0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-WgVZms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-eI79dD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-6W9YXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-IMZNKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-SOUUzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-nbziRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Di95M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-xwzQ7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-BW16Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-mDNzxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-sCacIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-w1P6st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-SBhe2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-XmOUPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:21:01 INFO - Running setup.py install for manifestparser 09:21:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:21:01 INFO - Running setup.py install for mozcrash 09:21:01 INFO - Running setup.py install for mozdebug 09:21:01 INFO - Running setup.py install for mozdevice 09:21:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:21:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:21:01 INFO - Running setup.py install for mozfile 09:21:01 INFO - Running setup.py install for mozhttpd 09:21:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Running setup.py install for mozinfo 09:21:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Running setup.py install for mozInstall 09:21:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Running setup.py install for mozleak 09:21:02 INFO - Running setup.py install for mozlog 09:21:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Running setup.py install for moznetwork 09:21:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:21:02 INFO - Running setup.py install for mozprocess 09:21:02 INFO - Running setup.py install for mozprofile 09:21:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:21:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:21:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:21:03 INFO - Running setup.py install for mozrunner 09:21:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:21:03 INFO - Running setup.py install for mozscreenshot 09:21:03 INFO - Running setup.py install for moztest 09:21:03 INFO - Running setup.py install for mozversion 09:21:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:21:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:21:03 INFO - Cleaning up... 09:21:03 INFO - Return code: 0 09:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:03 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/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:21:03 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:21:03 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:03 INFO - 'HOME': '/Users/cltbld', 09:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:03 INFO - 'LOGNAME': 'cltbld', 09:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:03 INFO - 'MOZ_NO_REMOTE': '1', 09:21:03 INFO - 'NO_EM_RESTART': '1', 09:21:03 INFO - 'PAGER': '/bin/cat', 09:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:03 INFO - 'PWD': '/builds/slave/test', 09:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:03 INFO - 'SHELL': '/bin/bash', 09:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:21:03 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:03 INFO - 'USER': 'cltbld', 09:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:03 INFO - 'XPC_FLAGS': '0x0', 09:21:03 INFO - 'XPC_SERVICE_NAME': '0', 09:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ufAKaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-zWEhVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-0nLPXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-zzCHL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-V8df0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-d4eC37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ZRAKlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-2a4KOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-rqs0ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:04 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)) 09:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:04 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Wa5_Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Xf6YfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-_VQtrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-tYX6QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-OGZMg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-5z0g4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-i7NNjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:05 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)) 09:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:05 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-2OZeRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:05 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)) 09:21:05 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)) 09:21:05 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)) 09:21:05 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)) 09:21:05 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)) 09:21:05 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)) 09:21:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:05 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 09:21:05 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 09:21:05 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 09:21:05 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 09:21:07 INFO - Downloading blessings-1.6.tar.gz 09:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:07 INFO - Installing collected packages: blessings 09:21:07 INFO - Running setup.py install for blessings 09:21:07 INFO - Successfully installed blessings 09:21:07 INFO - Cleaning up... 09:21:07 INFO - Return code: 0 09:21:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:21:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:07 INFO - Reading from file tmpfile_stdout 09:21:07 INFO - Current package versions: 09:21:07 INFO - blessings == 1.6 09:21:07 INFO - blobuploader == 1.2.4 09:21:07 INFO - docopt == 0.6.1 09:21:07 INFO - functools32 == 3.2.3-2 09:21:07 INFO - jsonschema == 2.5.1 09:21:07 INFO - manifestparser == 1.1 09:21:07 INFO - mozInstall == 1.12 09:21:07 INFO - mozcrash == 1.0 09:21:07 INFO - mozdebug == 0.1 09:21:07 INFO - mozdevice == 0.49 09:21:07 INFO - mozfile == 1.2 09:21:07 INFO - mozhttpd == 0.7 09:21:07 INFO - mozinfo == 0.9 09:21:07 INFO - mozleak == 0.1 09:21:07 INFO - mozlog == 3.4 09:21:07 INFO - moznetwork == 0.27 09:21:07 INFO - mozprocess == 0.25 09:21:07 INFO - mozprofile == 0.28 09:21:07 INFO - mozrunner == 6.13 09:21:07 INFO - mozscreenshot == 0.1 09:21:07 INFO - mozsystemmonitor == 0.3 09:21:07 INFO - moztest == 0.8 09:21:07 INFO - mozversion == 1.4 09:21:07 INFO - psutil == 3.1.1 09:21:07 INFO - requests == 1.2.3 09:21:07 INFO - wsgiref == 0.1.2 09:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:07 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/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:21:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:21:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:07 INFO - 'HOME': '/Users/cltbld', 09:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:07 INFO - 'LOGNAME': 'cltbld', 09:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:07 INFO - 'MOZ_NO_REMOTE': '1', 09:21:07 INFO - 'NO_EM_RESTART': '1', 09:21:07 INFO - 'PAGER': '/bin/cat', 09:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:07 INFO - 'PWD': '/builds/slave/test', 09:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:07 INFO - 'SHELL': '/bin/bash', 09:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:21:07 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:07 INFO - 'USER': 'cltbld', 09:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:07 INFO - 'XPC_FLAGS': '0x0', 09:21:07 INFO - 'XPC_SERVICE_NAME': '0', 09:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 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)) 09:21:08 INFO - Cleaning up... 09:21:08 INFO - Return code: 0 09:21:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:08 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/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 0x10fbccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b14783d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, '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.XmC99hma10/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.X9tQau1Whs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 09:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:08 INFO - 'HOME': '/Users/cltbld', 09:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:08 INFO - 'LOGNAME': 'cltbld', 09:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:08 INFO - 'MOZ_NO_REMOTE': '1', 09:21:08 INFO - 'NO_EM_RESTART': '1', 09:21:08 INFO - 'PAGER': '/bin/cat', 09:21:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:08 INFO - 'PWD': '/builds/slave/test', 09:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:08 INFO - 'SHELL': '/bin/bash', 09:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:21:08 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:08 INFO - 'USER': 'cltbld', 09:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:08 INFO - 'XPC_FLAGS': '0x0', 09:21:08 INFO - 'XPC_SERVICE_NAME': '0', 09:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:21:08 INFO - Cleaning up... 09:21:08 INFO - Return code: 0 09:21:08 INFO - Running post-action listener: _resource_record_post_action 09:21:08 INFO - Running post-action listener: _start_resource_monitoring 09:21:08 INFO - Starting resource monitoring. 09:21:08 INFO - [mozharness: 2017-06-06 16:21:08.414607Z] Finished create-virtualenv step (success) 09:21:08 INFO - [mozharness: 2017-06-06 16:21:08.415556Z] Running install step. 09:21:08 INFO - Running pre-action listener: _resource_record_pre_action 09:21:08 INFO - Running main action method: install 09:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:08 INFO - Reading from file tmpfile_stdout 09:21:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:21:08 INFO - Reading from file tmpfile_stdout 09:21:08 INFO - Output received: 09:21:08 INFO - Usage: mozinstall [options] installer 09:21:08 INFO - Options: 09:21:08 INFO - -h, --help show this help message and exit 09:21:08 INFO - -d DEST, --destination=DEST 09:21:08 INFO - Directory to install application into. [default: 09:21:08 INFO - "/builds/slave/test"] 09:21:08 INFO - --app=APP Application being installed. [default: firefox] 09:21:08 INFO - mkdir: /builds/slave/test/build/application 09:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:21:29 INFO - Reading from file tmpfile_stdout 09:21:29 INFO - Output received: 09:21:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:21:29 INFO - Running post-action listener: _resource_record_post_action 09:21:29 INFO - [mozharness: 2017-06-06 16:21:29.508312Z] Finished install step (success) 09:21:29 INFO - [mozharness: 2017-06-06 16:21:29.508451Z] Running run-tests step. 09:21:29 INFO - Running pre-action listener: _resource_record_pre_action 09:21:29 INFO - Running main action method: run_tests 09:21:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:29 INFO - 2017-06-06 09:21:29.522 screenresolution[1625:7458] starting screenresolution argv=screenresolution get 09:21:29 INFO - 2017-06-06 09:21:29.525 screenresolution[1625:7458] Display 0: 1600x1200x32@60 09:21:29 INFO - 2017-06-06 09:21:29.531 screenresolution[1626:7460] starting screenresolution argv=screenresolution list 09:21:29 INFO - Available Modes on Display 0 09:21:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:29 INFO - Intel Iris: 09:21:29 INFO - Chipset Model: Intel Iris 09:21:29 INFO - Type: GPU 09:21:29 INFO - Bus: Built-In 09:21:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:29 INFO - Vendor: Intel (0x8086) 09:21:29 INFO - Device ID: 0x0a2e 09:21:29 INFO - Revision ID: 0x0009 09:21:29 INFO - Displays: 09:21:29 INFO - Display: 09:21:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:29 INFO - Display Serial Number: 1600x1200 60 09:21:29 INFO - Main Display: Yes 09:21:29 INFO - Mirror: Off 09:21:29 INFO - Online: Yes 09:21:29 INFO - Rotation: Supported 09:21:29 INFO - Return code: 0 09:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:29 INFO - Python 2.7.3 09:21:29 INFO - Return code: 0 09:21:29 INFO - grabbing minidump binary from tooltool 09:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:21:29 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 0x10f9aad30>, '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 0x10f8d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f99fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:29 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 09:21:29 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 09:21:29 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 09:21:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:29 INFO - Return code: 0 09:21:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:21:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:29 INFO - ENV: RUST_BACKTRACE is now 1 09:21:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:21:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 09:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --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 09:21:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XmC99hma10/Render', 09:21:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:29 INFO - 'HOME': '/Users/cltbld', 09:21:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:29 INFO - 'LOGNAME': 'cltbld', 09:21:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:29 INFO - 'MOZ_NO_REMOTE': '1', 09:21:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:29 INFO - 'NO_EM_RESTART': '1', 09:21:29 INFO - 'PAGER': '/bin/cat', 09:21:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:29 INFO - 'PWD': '/builds/slave/test', 09:21:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:29 INFO - 'RUST_BACKTRACE': '1', 09:21:29 INFO - 'SHELL': '/bin/bash', 09:21:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X9tQau1Whs/Listeners', 09:21:29 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 09:21:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:29 INFO - 'USER': 'cltbld', 09:21:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:29 INFO - 'XPC_FLAGS': '0x0', 09:21:29 INFO - 'XPC_SERVICE_NAME': '0', 09:21:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 09:21:30 INFO - mozversion application_buildid: 20170606081521 09:21:30 INFO - mozversion application_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:21:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:21:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:30 INFO - mozversion application_name: Firefox 09:21:30 INFO - mozversion application_remotingname: firefox-dev 09:21:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:21:30 INFO - mozversion application_vendor: Mozilla 09:21:30 INFO - mozversion application_version: 54.0 09:21:30 INFO - mozversion platform_buildid: 20170606081521 09:21:30 INFO - mozversion platform_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:21:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:21:30 INFO - mozversion platform_version: 54.0 09:21:30 INFO - using testdate: 1496766090 09:21:30 INFO - actual date: 1496766090 09:21:30 INFO - starting webserver on 'localhost:49226' 09:21:30 INFO - SUITE-START | Running 2 tests 09:21:30 INFO - TEST-START | dromaeo_css 09:21:30 INFO - operating with platform_type : mac_ 09:21:30 INFO - Initialising browser for dromaeo_css test... 09:21:30 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpjGVir4/profile http://localhost:49226/getInfo.html) 09:21:33 INFO - PROCESS | 1636 | 2017-06-06 09:21:33.087 plugin-container[1639:7639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944b, name = 'com.apple.tsm.portname' 09:21:33 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:21:33 INFO - PROCESS | 1636 | 2017-06-06 09:21:33.087 plugin-container[1639:7639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:21:33 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:21:33 INFO - PROCESS | 1636 | 2017-06-06 09:21:33.087 plugin-container[1639:7639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:21:33 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 09:21:33 INFO - PROCESS | 1636 | colorDepth:24 09:21:33 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 09:21:33 INFO - PROCESS | 1636 | __metrics 09:21:33 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 09:21:33 INFO - TEST-INFO | 1636: exit 0 09:21:33 INFO - Browser initialized. 09:21:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:21:33 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpjGVir4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:36 INFO - PROCESS | 1640 | 2017-06-06 09:21:36.246 plugin-container[1641:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:21:36 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:21:36 INFO - PROCESS | 1640 | 2017-06-06 09:21:36.246 plugin-container[1641:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8613, name = 'com.apple.CFPasteboardClient' 09:21:36 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:21:36 INFO - PROCESS | 1640 | 2017-06-06 09:21:36.246 plugin-container[1641:7776] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:21:46 INFO - PROCESS | 1640 | RSS: Main: 202960896 09:21:46 INFO - PROCESS | 1640 | 09:24:49 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 09:24:51 INFO - PROCESS | 1640 | RSS: Main: 216072192 09:24:51 INFO - PROCESS | 1640 | 09:27:53 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 09:27:54 INFO - PROCESS | 1640 | RSS: Main: 230731776 09:27:54 INFO - PROCESS | 1640 | 09:30:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 09:30:56 INFO - PROCESS | 1640 | RSS: Main: 232058880 09:30:56 INFO - PROCESS | 1640 | 09:33:58 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 09:33:59 INFO - PROCESS | 1640 | RSS: Main: 236249088 09:33:59 INFO - PROCESS | 1640 | 09:37:02 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 09:37:02 INFO - PROCESS | 1640 | RSS: Main: 238559232 09:37:02 INFO - PROCESS | 1640 | 09:40:05 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 09:40:06 INFO - PROCESS | 1640 | RSS: Main: 236584960 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | __start_tp_report 09:40:06 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 09:40:06 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 09:40:06 INFO - PROCESS | 1640 | |i|pagename|runs| 09:40:06 INFO - PROCESS | 1640 | |0;dojo.html;2889.7977141600086;3261.6575259597726;3489.1102768793953;3513.2622149348636;3569.2861427714456;4653.7440440775745;5964.79123230687;6060.424259695327;6070.878582428349;6097.5426842986735;6060.545459090564;6091.756329746816;6119;6138.693065346726;6173.876522469548;5405.216243644679;5490.725463726818;5503.367112782022;5514.834554963358;5521.199426083215;4713.835015774449;5440.319960004999;5537.916931246034;5556.805511807088;5584.553235741131;5309.203619457074;5319.361676598814;5525.861853453655;5527.170924361358;5589.161625756149;5507.173923911405;5521.723913804293;5552.305961754781;5567.8608034799045;5606;5381.219723140122;5438;5442.891142177174;5474.91787623186;5486.972565137189;5777.104548794943;6067;6099.146119543268;6164.013757798775;6202.162708034448;5993.61041532299;6346.2067241594805;6367.745290188387;6374.745010199586;6401.327860574619;6519.771807986745;6520.869582608369;6532.183477065367;6551.148350714424;6619.569727967667;5136.486351364871;6235.594686345373;6247;6260.280067792217;6262.185915830913;6320.8419789504715;6376.649284289316;6391.872162556723;6426.2931077581425;6526.314736952647;6347.39699728525;6386.329435409215;6389.52078594098;6390.584612000205;6410.967945160338;6450.838729031718;6462.4183823456115;6476.222853257545;6490.870182596322;6492.5455218134275;6254.437100660963;6318.526110541728;6344.365563443619;6403.551751377358;6432.388923052303;6339.714712837934;6342.334054924258;6360.204974378203;6432.614043157426;6452.451541619015;4897.632677549127;4913.336699545587;4954.20732682769;4969.304297398295;4979.90040199194;3808.4287356896134;4215.114825886598;4452.844150454784;4468.3074123510905;4495.415595972503;5769.048107062287;6062.030075187949;6086.665233412116;6092.238470191226;6097.451229389376;6296.748130074838;6336.651484168415;6344.746210151635;6369.777057803048;6396.488280937514;5934.673592952429;5967.104934259809;5978.760849566055;5986.670733109634;6012.038073908154;4660.021395506914;5321.760520776575;5588.133839254922;5609.102543593006;5614.29821272341;6227.719752611053;6406.455451286506;6495.9025614614875;6516.609003459807;6630.535862489773;6495.480361571157;6502.967485162544;6513.5114866384265;6515.185601799775;6523.902141467787;6669.966650166718;6683.665816709048;6706.597604143767;6719.865602687821;6725.125733654593;6279.215098112736;6327.810165695037;6328.462080723006;6352.809415717536;6466.967665161644;6470.6441145737435;6474.158359413246;6485.481161506974;6492.318306577741;6514.967425162844;5601.887962240814;6253.1245625613365;6303.810885673437;6355.3326900674765;6361.840953976002;6165.260168779596;6236.501079913687;6266.216722909637;6277.623342599459;6361.777337793155;6216.409441103088;6276.560640755103;6321.715522801577;6330.208723909512;6348.143000695032;6373.267074286517;6373.74505019794;6375.6174629522375;6383.042543618309;6398.840028999312;6101.450869421596;6120.386183820725;6167.013277875519;6238.968805155945;6254.9687251563455 09:40:06 INFO - PROCESS | 1640 | |1;ext.html;763.198641426502;788.1645455816835;834.1450013735922;864.4553931023454;967.0764419978921;34916;40046.99882502939;41930.37104443428;41968.790156049174;42076.15847683048;40573.79713101438;40732;40901.590984090224;40908.77273681787;40974;8139;11171.66485005447;11394.430278486085;11573.36346500942;11602.651920442358;9428.434293942351;11134.49894754738;11184.944075279613;11221.158413119008;11236.943815280913;11186.608468703596;11227.101831853432;11236.438178091104;11251.437428128602;11253.156013298994;11081.224314298002;11157.330560166376;11162.720931976684;11176.832347514794;11179.993800557908;11001.339919604849;11026.283291586022;11050.557977680883;11068;11075.446227688582;35401.822990885135;35632.46551301733;35656.93029209102;35676.64323356759;35751.642483575095;3816.2939856126704;3867.361885288924;3912.5500567434824;3913.6673382762215;3958.366661334173;26110.216693499224;26183.607245891137;26252.474950501073;27689.446211075665;27871.581926271123;3107.5494053362136;3153.5269709543522;3196.8881089162;3200.0559834848777;3300.042987533612;313826;318226;344238;345274.27362863277;349277.2536137329;44379.77810110929;44681.77659111684;44822.32766508505;44907.77546112249;44938;46281;46684.06631867344;46688.299675504895;46697.766511167225;46802.06395872063;13857.51498697561;13932.721345573033;13961.232132232824;14003.229822359664;14034.719305613831;12244.326562039174;12289.262644241195;12292.508299668092;12307.015438765056;12316.692082698004;10776.515056822462;10971.177161712743;12054.758904821854;12140.472906797666;12199.085068619712;4444.355568442557;5401.513863752281;5778.913316300259;5874.118882167623;5880.852978675482;736.3189050128609;741.6514238307986;824.4929368438367;868.9391742577959;886.3618194899661;23442.413939651306;26437.074702385326;26569.46861062768;27380.863095684792;27758.722412775613;953.1945506047517;958.520739630185;991.8462638291077;992.5831150916632;995.4773743784571;13738.313084345742;14411.711765764627;14472.927635361755;14952.70094598102;15372.077675339291;498447.0155298473;501071;503346;503380;504287;50927.49072509228;51044;51226;51239.487605123475;51498.48501514937;1322.3057894605408;1361.6731984323692;1362.3937347880296;1363.3047145955634;1366.1120271823395;27958;28089.859550702116;28582.42835143327;28807;29193.270168245963;42728;42965.57034429616;43077;43100.56899430966;43127.56872431361;445268;448275;450821;451213;451449;29301.70698293075;30509.54235686511;30578.08265752031;30723.539146912368;32619.18452038718;38861.80569097136;39271;40054.59945400509;40192;40327;27621.44757104887;28227.43545129127;28614.998475054104;29280.121596352143;29466;28637.570436443053;28754.281142971595;28825.279368015967;29058.27354316159;29552.852235738683;32981.01056968295;33762.49356259707;33969.660303397635;34354.48468272928;34546.309073818884;23377.46366713106;25300.87349563314;28015.85992070108;29426.705732942395;29529.85235073811;22632.773672263065;22659.660105098108;23656.763432365457;23744;23815.8092095398 09:40:06 INFO - PROCESS | 1640 | |2;jquery.html;372.7632953074798;407.1490584678023;408.8528129873247;408.96523795477384;448.3588468490058;20807;20816.375508734724;22377.888110559466;22403.21588744394;22414.88792556039;22511.09955601778;22543.210987615435;22718.432039198986;22915;23014.07943682255;6868.381845633904;6990.895136572955;7018.4736144789085;7021.122359705037;7121.4302855771675;6634.270230274666;7168.283171682816;7178.138623365204;7185.461090418214;7201.279872012783;6873.828154296132;6881.208661003999;6983.196932352794;7151.821204469878;7156;6901.827454313632;6929.272426395231;6980.755673551426;6985.6507174641065;7018;6483.189601299838;6889.655517224119;6900.413464855494;6901.999210114505;6915.619640919747;27037.594436083473;27585.724142758718;27663.58504622432;27664.308392290153;27957.86021069902;2836.262571731344;2969.5545668149734;3026.9091927242216;3439.3121375724954;3443.517907492953;16216.594585135348;16554.503364899072;16804.90768100082;16850.410235641695;17366.131693415406;1833.7982821889582;2116.619008578455;2174.8695078295195;2193.8683678979314;2210.889455527233;158904.20547897185;162653;163294;164707;168416.15791921085;21650.133994640037;21854.45363865916;22106;22260;22310.55378892413;27286.727132728418;32672.346553068804;32827.34345313128;33207.3358532828;33231.66768332334;10660.040596346365;10793.40636265013;10905.945470272598;10996.14810077879;11062.391568463812;6799.796006119825;7398.889016664754;8125.9842519685035;9049.954750226338;9229.769255768526;8249.257566819015;9961.800763984824;9997.400155990665;10082.991700829858;10104.646337378303;3652.926941461156;4368.825246990148;4371.868843934687;4379.386885835986;4460.821567137343;912.8676341930383;1032.2155162076726;1036.875574931013;1068.2095249515303;1094.261373572835;20986.950652467316;22241.11035558592;22264.220752273915;22266.220682276366;22271.888640556692;1067.9466026698633;1068.899033994971;1082.7726177502814;1092.8415379769888;1109.683740134074;10888.346699198073;11566.392632031126;11622.186446948801;11624.593139240085;11656.708582285512;742377;744577;747366;752619;762829;121417;146028;151932;154294;156295;1219.1344145656506;1222.6270987348944;1262.31834809202;1267.6133779197248;1276.6595075171033;15192.620184495476;18553.25786968506;19380.709289360388;20946.371608851758;21118.155273788878;60131;62245;63214.367856320845;63414.365856340846;63792;88008.39937004622;90041;90587;91609;92751;9128.361014729171;10110.595576176871;11494.195406321478;11738.471768770254;11893.583724569944;11765.647030589096;11774.175807693378;11993.82009269879;12237.449314780679;13304;9528.61885524599;11353.069978103907;11647.883521165018;11746.882531174579;11817.645470635895;10585.4707264638;10657.680269591925;10702.571897124028;10799.946000269949;10978.176636752436;11626.849032928934;11677.649670509898;11882.227655202385;11932.821007685065;11996.76006479883;11521.423928803693;11572.074234061087;11631.47658355393;11881.643550693492;11938.22401543897;10773.01002416897;11300;11357.602483913353;11515.654530363767;11693.006094482063 09:40:06 INFO - PROCESS | 1640 | |3;mootools.html;1574.669319442917;1602.8940031378347;1610.7181243282425;1613.0483015021134;1617.401561422274;10792.838107428384;11204.551817927273;11288.830667539984;11421.885781142197;11523.827142592867;11241.494132764024;11381;11409.714757131076;11417.086633069355;11440.084793216543;2346.0498498108295;2408.494216214588;2412.264259400882;2414.625733011377;2415.710114786228;2333.5449587330477;2412.5054363855365;2416.66166736657;2423.466837295792;2435.7198922123916;2352.870592117433;2404.1224952892157;2415.408224984881;2427.8786060696984;2427.987860060706;2330.196082351586;2389.426537630965;2396;2396.916107936231;2407.1935901473025;2364.5861974154627;2373.738888722239;2383.653235921716;2390.6772585701056;2401.5076909233567;9496.382735122264;11039.668809935714;11271.211015228937;11292.266002709797;11314.717132071682;5746.42535746422;5919.940800592025;5933.050711886078;5936.851578710523;5951.821445356646;3524.312759011994;3606.2787442511603;3655.981720091409;3657.4330978698345;3663.8351274192723;1117.9552817887275;1199.3703305764457;1221.4381384563112;1281.385454327554;1287.7231395249974;11146.944265278622;12570.68573285666;14008.579742607739;14456.78314825268;15454.768178477332;4175.874723758292;4545.636349092131;4607.101615185074;4741.976290118596;4749.810007599726;10382.584696612203;11557.959783619515;11743.76512469763;11901.702457438634;11924.821127683092;5711.3146422428745;5743.511801496919;5788.739506722208;5790.986577348947;5805.82582522525;5169.71566563835;5182.196759502283;5256.553192978563;5299.68201907879;5327.680339179662;3274.8690052398115;3834.7699138051807;3913.7651740895067;3914.3149948758855;3968.0873399118364;1002.8997100289913;1014.1633152649094;1018.8981101889751;1024.4519182237616;1027.1423361493087;807.7738233294687;841.760098371961;899.6401439424152;906.3429013964954;933.5892207428709;4911.656184067152;5846.853828654318;6433.839154021187;6460.160179176771;6560.737570497126;922.4649703172144;1003.8042581696499;1014.4420568687193;1058.751193469534;1083.8374243863325;3922.843086276517;4106.425100485934;4212.599803018708;4238.554951730086;4239.533651298355;13115.147515411467;13612.31938403076;13636.181829090287;13662.726745465035;13671.906247500176;13399.263040532864;13444.663883402993;13454.32728363578;13788.103773254707;13813.447462101403;8823;10275.537600807982;10316.535755890853;10423.061924426838;10475.16198704117;6188.752449901954;6599.142111525471;6607.0419789131265;6817.5909445433435;6841.93158068413;11648.146204179691;11785.941070294593;12096.334701591497;12174.695632609255;12187.878121218675;11965.880341196476;12070.637880863587;12120;12177.939110304746;12259.938700306442;9161.725148245563;9283;9427.57575909072;9446.669366572138;9466.290028247771;10321.690349289533;10350.896491034993;10404.271700980858;10433;10458;8676.436031657922;8882.689105881522;8904;8914.643414263617;9096.454212747256;8667.739967800977;8669.913300867163;8722.302215822845;8734.56327183651;8766.868496972424;7809.297163255334;7872.921270787218;7937.047554293291;7956.283934445928;7963.960180199062;5761.308642962871;5793.246877906014;5811.4188581143235;5947.672877991752;6029.125776762433;5773.711314434177;5880.911786323123;6024.186734790747;6064.6960903404215;6129.172561704113 09:40:06 INFO - PROCESS | 1640 | |4;prototype.html;302;329.2854505722582;386.5361566120656;386.79113278829453;389.8460108257239;4956.67781594197;5233.502817232362;5278.258174802316;5337.386200586933;5350.705711185883;4256.936145957813;4279.33670281107;4796.448408433032;5094.184930411135;5251.133562962106;3825.8087095645246;4129.525104612963;4178.832846686144;4342.826286948534;4353.869383918487;3628.1859070464766;4290.76400797956;4305.67707421943;4325.956740432587;4366.345048242773;3793.5447746270347;3827.8086095695244;3828.8277027533827;3965.583613720561;4327.394164816928;3537.451694987268;3800.90497737556;3987.4018897165365;4012.9398059029136;4291.613754762071;3530.9117272068147;3683.244934788362;3918.8628398006217;3951.4467974483596;3983.920321593581;4660;4884.900897298115;4999.275105109775;5038.531119751332;5076.64463487556;4314.913701725979;4735.5974742146955;4985.675931064469;5028.673136246132;5040.470750571174;4703.247480403119;5019.949800502021;5123.359580052494;5155.432902380735;5633.098704207348;3497.720182385403;3843.731568582389;4463.977680111611;4471.597556219924;4499.460064792246;5186.870328241786;5877.294724633071;5924.45964049346;5940.594059405904;5954.5534084943365;4323;4359.324304732771;4403.732077979843;4570.565796249351;4593.678442509059;4600.401947746838;4644.814207431665;4647.790849411785;4650.3956135134;4675.6727029108315;4693;4928.507149285115;4963.205887058053;5051.055343692705;5065.772040258197;4808;5253.684778913277;5456.427075157131;5530.695811730394;5574.888502229933;1316.960491185266;1377.5591810620506;1583.1609247099057;1587.8253392126858;1592.554084856242;1008.3042700536606;1098.7912296663608;1159.0032571988083;1192.3442106841376;1197.9401029948467;4861.805527778849;4923.852284431473;4968.714391379934;4986.800527978913;5008.84973450797;5173.379194496684;5255.054090263713;5698.6580805151825;5744.741486633112;5793.768249270066;4573.359729637853;4753.407608451138;4856.878578035577;4982.950170498321;5015.548600625962;2462.8891699873548;2743.903963361273;2800.523910935146;2804.8737806798745;3013.668496465387;4731.266653668687;5019.774110165052;5074.670146440543;5502.80740174092;5789.002794036051;4781.760911954389;5410.670489567772;5481.725913704299;5638;5907.261592300963;4892.336605554128;5374.253367655528;5951.4048595141;5983.850403739942;5990.700464976734;4435.689501734848;5590.916136258041;5687.544996400361;5754.9136762947755;5759.596828222152;4856.587190088882;5403;5488.4785945335125;5686.402927692533;5708.257926469532;5072.452901864966;5534.335879694462;5566;5593.497234726085;5793.420657934173;4038.91922161561;4091.1408604193452;4532.388127602731;4577.771111444481;4594.517575654508;5297.947020529746;5327.9610475956815;5517.393086760533;5843.2403787507355;5890.823275301748;4613.423322084739;4625.872098834439;4885.676176583678;5014.899702006012;5616.522595579258;4398.30665193909;5423.810166644306;5611.971940140274;5664.518515926027;5674.7370262100285;5071.721055341992;5104.821331253388;5131.511861560106;5485.790812374646;5515.145152501369;4867.415910090812;5555.833275012293;5642.576806739593;5650.576206784425;5674.233978412862;4440.7335559866515;4813.31781187385;4896.804127834989;5228.9671990246325;5489.204065410413 09:40:06 INFO - PROCESS | 1640 | |5;yui.html;721.58508857407;725.4559080689482;737.793417843004;792.2988155482396;855.9443636163649;15405;16318.020918744869;16885.49343519693;17095;17294.2217600208;15011.399544018253;15040.774388384183;15763.684726305495;15907.840921590781;16091.678166436694;960.3661583354988;962.311946957925;962.3840741925138;962.4754508792707;964.0793042644258;936.4615346175942;939.8261321655482;956.6077908057698;986.8865080515725;1001.5743309093606;858.7981824271292;1021.6781713760141;1023.6366090038041;1024.748936510556;1024.87701475823;884.5135175653365;1019.3679918450571;1019.6991887393162;1020.4030642074321;1022.6522982186056;755.9055118110236;759.0625577411892;760.1258552664425;760.7033257029763;760.9809754756176;15052;15342.769858452131;16024.718022558167;17068.658626827517;17091.743623845654;14427;14485.637859053502;14523.346449409697;14525.201113938729;14700.779488307684;11654.417279136009;11784.351860647661;11805;11948.880511194864;12307;3693.4275187346007;3786.659200671953;3803.904902377435;3805.8858234253016;3950.8419663213435;96925;114350.42824785906;118487;121277;122451;10963.177761667906;11067.944660276808;11453.370064646524;11479.13906457019;11536.884631153582;23242.83785810703;23471.7652823473;24043.879780600986;24147.7585224149;24152.87923560371;7645.694172233048;8010.279074883289;8077.151899050633;8107.635156417975;8178.018637763506;4333.6316413104605;4365.388845561624;4450.376947227391;4525.298578720303;4533.977330113328;3517.944616615005;3613.2050948791225;3613.9277214456088;3618.620044895301;3619.475176099451;626.5488848029412;629.7921685843752;631.4064779107624;646.1277275677797;655.8917778566579;635.9141515895387;663.2538394306405;685.5954986557955;687.2680595166132;691.339770519153;10417.427041512638;10427.980668626346;10433.530491127765;10806.243562950674;10839.729006774736;572.4647454629934;614.9617626267327;651.364919203782;661.355178700763;665.1585744033802;5014.022265658235;5072.036313100499;5077.365329333833;5119.180931051014;5140.563052140536;78697;89017;105963;109042.45478772714;110809.44595276972;72476;92175.5391223053;92523;95895;98109.50945245229;80701;83211.5839420799;83807.5809620948;83915.32169356578;84590;9598.808023839345;9897.901020989699;9961.40231586107;9989.500524973577;10006.749831254278;52747;54965.450345496036;55338.72330638482;55704;55895.72052139713;47977.04045918992;57134.42865571291;64715.67642161759;67379.31551711247;67514.6497070046;12781.424835882222;12914.612561622791;13125.60623181306;13376.929845612132;13774.793378099537;14786.260686965823;14799.778003329744;15073;15260.923695381452;15487.612809679398;12795.104342695922;12958.740825183631;13697.794533081813;14233.928830355782;14418.351174196976;13838.515651952133;13902.23537705436;14191.219482928538;14398.568042958728;14650.120992740469;13385;13671.17972921628;13814.240216788174;13871.959603030014;13992.300384980914;9804.215662746821;10147.188224942134;10240.385576865412;10480.074679392574;10589.364638121737;11288.322700637988;11378.829317560077;11396.145289103517;11633.941830290794;11637.825432618349 09:40:06 INFO - PROCESS | 1640 | __end_tp_report 09:40:06 INFO - PROCESS | 1640 | __start_cc_report 09:40:06 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,379 09:40:06 INFO - PROCESS | 1640 | __end_cc_report 09:40:06 INFO - PROCESS | 1640 | __startTimestamp1496767206040__endTimestamp 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | ------- Summary: start ------- 09:40:06 INFO - PROCESS | 1640 | Number of tests: 6 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5945.70 Median:6247.00 stddev:721.28 (11.5%) stddev-sans-first:682.49 09:40:06 INFO - PROCESS | 1640 | Values: 2889.8 3261.7 3489.1 3513.3 3569.3 4653.7 5964.8 6060.4 6070.9 6097.5 6060.5 6091.8 6119.0 6138.7 6173.9 5405.2 5490.7 5503.4 5514.8 5521.2 4713.8 5440.3 5537.9 5556.8 5584.6 5309.2 5319.4 5525.9 5527.2 5589.2 5507.2 5521.7 5552.3 5567.9 5606.0 5381.2 5438.0 5442.9 5474.9 5487.0 5777.1 6067.0 6099.1 6164.0 6202.2 5993.6 6346.2 6367.7 6374.7 6401.3 6519.8 6520.9 6532.2 6551.1 6619.6 5136.5 6235.6 6247.0 6260.3 6262.2 6320.8 6376.6 6391.9 6426.3 6526.3 6347.4 6386.3 6389.5 6390.6 6411.0 6450.8 6462.4 6476.2 6490.9 6492.5 6254.4 6318.5 6344.4 6403.6 6432.4 6339.7 6342.3 6360.2 6432.6 6452.5 4897.6 4913.3 4954.2 4969.3 4979.9 3808.4 4215.1 4452.8 4468.3 4495.4 5769.0 6062.0 6086.7 6092.2 6097.5 6296.7 6336.7 6344.7 6369.8 6396.5 5934.7 5967.1 5978.8 5986.7 6012.0 4660.0 5321.8 5588.1 5609.1 5614.3 6227.7 6406.5 6495.9 6516.6 6630.5 6495.5 6503.0 6513.5 6515.2 6523.9 6670.0 6683.7 6706.6 6719.9 6725.1 6279.2 6327.8 6328.5 6352.8 6467.0 6470.6 6474.2 6485.5 6492.3 6515.0 5601.9 6253.1 6303.8 6355.3 6361.8 6165.3 6236.5 6266.2 6277.6 6361.8 6216.4 6276.6 6321.7 6330.2 6348.1 6373.3 6373.7 6375.6 6383.0 6398.8 6101.5 6120.4 6167.0 6239.0 6255.0 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:55723.55 Median:160711.44 stddev:115503.75 (71.9%) stddev-sans-first:115754.05 09:40:06 INFO - PROCESS | 1640 | Values: 763.2 788.2 834.1 864.5 967.1 34916.0 40047.0 41930.4 41968.8 42076.2 40573.8 40732.0 40901.6 40908.8 40974.0 8139.0 11171.7 11394.4 11573.4 11602.7 9428.4 11134.5 11184.9 11221.2 11236.9 11186.6 11227.1 11236.4 11251.4 11253.2 11081.2 11157.3 11162.7 11176.8 11180.0 11001.3 11026.3 11050.6 11068.0 11075.4 35401.8 35632.5 35656.9 35676.6 35751.6 3816.3 3867.4 3912.6 3913.7 3958.4 26110.2 26183.6 26252.5 27689.4 27871.6 3107.5 3153.5 3196.9 3200.1 3300.0 313826.0 318226.0 344238.0 345274.3 349277.3 44379.8 44681.8 44822.3 44907.8 44938.0 46281.0 46684.1 46688.3 46697.8 46802.1 13857.5 13932.7 13961.2 14003.2 14034.7 12244.3 12289.3 12292.5 12307.0 12316.7 10776.5 10971.2 12054.8 12140.5 12199.1 4444.4 5401.5 5778.9 5874.1 5880.9 736.3 741.7 824.5 868.9 886.4 23442.4 26437.1 26569.5 27380.9 27758.7 953.2 958.5 991.8 992.6 995.5 13738.3 14411.7 14472.9 14952.7 15372.1 498447.0 501071.0 503346.0 503380.0 504287.0 50927.5 51044.0 51226.0 51239.5 51498.5 1322.3 1361.7 1362.4 1363.3 1366.1 27958.0 28089.9 28582.4 28807.0 29193.3 42728.0 42965.6 43077.0 43100.6 43127.6 445268.0 448275.0 450821.0 451213.0 451449.0 29301.7 30509.5 30578.1 30723.5 32619.2 38861.8 39271.0 40054.6 40192.0 40327.0 27621.4 28227.4 28615.0 29280.1 29466.0 28637.6 28754.3 28825.3 29058.3 29552.9 32981.0 33762.5 33969.7 34354.5 34546.3 23377.5 25300.9 28015.9 29426.7 29529.9 22632.8 22659.7 23656.8 23744.0 23815.8 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:43463.16 Median:22250.56 stddev:125164.80 (562.5%) stddev-sans-first:125474.10 09:40:06 INFO - PROCESS | 1640 | Values: 372.8 407.1 408.9 409.0 448.4 20807.0 20816.4 22377.9 22403.2 22414.9 22511.1 22543.2 22718.4 22915.0 23014.1 6868.4 6990.9 7018.5 7021.1 7121.4 6634.3 7168.3 7178.1 7185.5 7201.3 6873.8 6881.2 6983.2 7151.8 7156.0 6901.8 6929.3 6980.8 6985.7 7018.0 6483.2 6889.7 6900.4 6902.0 6915.6 27037.6 27585.7 27663.6 27664.3 27957.9 2836.3 2969.6 3026.9 3439.3 3443.5 16216.6 16554.5 16804.9 16850.4 17366.1 1833.8 2116.6 2174.9 2193.9 2210.9 158904.2 162653.0 163294.0 164707.0 168416.2 21650.1 21854.5 22106.0 22260.0 22310.6 27286.7 32672.3 32827.3 33207.3 33231.7 10660.0 10793.4 10905.9 10996.1 11062.4 6799.8 7398.9 8126.0 9050.0 9229.8 8249.3 9961.8 9997.4 10083.0 10104.6 3652.9 4368.8 4371.9 4379.4 4460.8 912.9 1032.2 1036.9 1068.2 1094.3 20987.0 22241.1 22264.2 22266.2 22271.9 1067.9 1068.9 1082.8 1092.8 1109.7 10888.3 11566.4 11622.2 11624.6 11656.7 742377.0 744577.0 747366.0 752619.0 762829.0 121417.0 146028.0 151932.0 154294.0 156295.0 1219.1 1222.6 1262.3 1267.6 1276.7 15192.6 18553.3 19380.7 20946.4 21118.2 60131.0 62245.0 63214.4 63414.4 63792.0 88008.4 90041.0 90587.0 91609.0 92751.0 9128.4 10110.6 11494.2 11738.5 11893.6 11765.6 11774.2 11993.8 12237.4 13304.0 9528.6 11353.1 11647.9 11746.9 11817.6 10585.5 10657.7 10702.6 10799.9 10978.2 11626.8 11677.6 11882.2 11932.8 11996.8 11521.4 11572.1 11631.5 11881.6 11938.2 10773.0 11300.0 11357.6 11515.7 11693.0 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6675.45 Median:2415.56 stddev:4125.24 (170.8%) stddev-sans-first:4119.01 09:40:06 INFO - PROCESS | 1640 | Values: 1574.7 1602.9 1610.7 1613.0 1617.4 10792.8 11204.6 11288.8 11421.9 11523.8 11241.5 11381.0 11409.7 11417.1 11440.1 2346.0 2408.5 2412.3 2414.6 2415.7 2333.5 2412.5 2416.7 2423.5 2435.7 2352.9 2404.1 2415.4 2427.9 2428.0 2330.2 2389.4 2396.0 2396.9 2407.2 2364.6 2373.7 2383.7 2390.7 2401.5 9496.4 11039.7 11271.2 11292.3 11314.7 5746.4 5919.9 5933.1 5936.9 5951.8 3524.3 3606.3 3656.0 3657.4 3663.8 1118.0 1199.4 1221.4 1281.4 1287.7 11146.9 12570.7 14008.6 14456.8 15454.8 4175.9 4545.6 4607.1 4742.0 4749.8 10382.6 11558.0 11743.8 11901.7 11924.8 5711.3 5743.5 5788.7 5791.0 5805.8 5169.7 5182.2 5256.6 5299.7 5327.7 3274.9 3834.8 3913.8 3914.3 3968.1 1002.9 1014.2 1018.9 1024.5 1027.1 807.8 841.8 899.6 906.3 933.6 4911.7 5846.9 6433.8 6460.2 6560.7 922.5 1003.8 1014.4 1058.8 1083.8 3922.8 4106.4 4212.6 4238.6 4239.5 13115.1 13612.3 13636.2 13662.7 13671.9 13399.3 13444.7 13454.3 13788.1 13813.4 8823.0 10275.5 10316.5 10423.1 10475.2 6188.8 6599.1 6607.0 6817.6 6841.9 11648.1 11785.9 12096.3 12174.7 12187.9 11965.9 12070.6 12120.0 12177.9 12259.9 9161.7 9283.0 9427.6 9446.7 9466.3 10321.7 10350.9 10404.3 10433.0 10458.0 8676.4 8882.7 8904.0 8914.6 9096.5 8667.7 8669.9 8722.3 8734.6 8766.9 7809.3 7872.9 7937.0 7956.3 7964.0 5761.3 5793.2 5811.4 5947.7 6029.1 5773.7 5880.9 6024.2 6064.7 6129.2 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4552.68 Median:4894.57 stddev:1286.13 (26.3%) stddev-sans-first:1249.54 09:40:06 INFO - PROCESS | 1640 | Values: 302.0 329.3 386.5 386.8 389.8 4956.7 5233.5 5278.3 5337.4 5350.7 4256.9 4279.3 4796.4 5094.2 5251.1 3825.8 4129.5 4178.8 4342.8 4353.9 3628.2 4290.8 4305.7 4326.0 4366.3 3793.5 3827.8 3828.8 3965.6 4327.4 3537.5 3800.9 3987.4 4012.9 4291.6 3530.9 3683.2 3918.9 3951.4 3983.9 4660.0 4884.9 4999.3 5038.5 5076.6 4314.9 4735.6 4985.7 5028.7 5040.5 4703.2 5019.9 5123.4 5155.4 5633.1 3497.7 3843.7 4464.0 4471.6 4499.5 5186.9 5877.3 5924.5 5940.6 5954.6 4323.0 4359.3 4403.7 4570.6 4593.7 4600.4 4644.8 4647.8 4650.4 4675.7 4693.0 4928.5 4963.2 5051.1 5065.8 4808.0 5253.7 5456.4 5530.7 5574.9 1317.0 1377.6 1583.2 1587.8 1592.6 1008.3 1098.8 1159.0 1192.3 1197.9 4861.8 4923.9 4968.7 4986.8 5008.8 5173.4 5255.1 5698.7 5744.7 5793.8 4573.4 4753.4 4856.9 4983.0 5015.5 2462.9 2743.9 2800.5 2804.9 3013.7 4731.3 5019.8 5074.7 5502.8 5789.0 4781.8 5410.7 5481.7 5638.0 5907.3 4892.3 5374.3 5951.4 5983.9 5990.7 4435.7 5590.9 5687.5 5754.9 5759.6 4856.6 5403.0 5488.5 5686.4 5708.3 5072.5 5534.3 5566.0 5593.5 5793.4 4038.9 4091.1 4532.4 4577.8 4594.5 5297.9 5328.0 5517.4 5843.2 5890.8 4613.4 4625.9 4885.7 5014.9 5616.5 4398.3 5423.8 5612.0 5664.5 5674.7 5071.7 5104.8 5131.5 5485.8 5515.1 4867.4 5555.8 5642.6 5650.6 5674.2 4440.7 4813.3 4896.8 5229.0 5489.2 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21025.90 Median:13835.41 stddev:30163.40 (218.0%) stddev-sans-first:30209.48 09:40:06 INFO - PROCESS | 1640 | Values: 721.6 725.5 737.8 792.3 855.9 15405.0 16318.0 16885.5 17095.0 17294.2 15011.4 15040.8 15763.7 15907.8 16091.7 960.4 962.3 962.4 962.5 964.1 936.5 939.8 956.6 986.9 1001.6 858.8 1021.7 1023.6 1024.7 1024.9 884.5 1019.4 1019.7 1020.4 1022.7 755.9 759.1 760.1 760.7 761.0 15052.0 15342.8 16024.7 17068.7 17091.7 14427.0 14485.6 14523.3 14525.2 14700.8 11654.4 11784.4 11805.0 11948.9 12307.0 3693.4 3786.7 3803.9 3805.9 3950.8 96925.0 114350.4 118487.0 121277.0 122451.0 10963.2 11067.9 11453.4 11479.1 11536.9 23242.8 23471.8 24043.9 24147.8 24152.9 7645.7 8010.3 8077.2 8107.6 8178.0 4333.6 4365.4 4450.4 4525.3 4534.0 3517.9 3613.2 3613.9 3618.6 3619.5 626.5 629.8 631.4 646.1 655.9 635.9 663.3 685.6 687.3 691.3 10417.4 10428.0 10433.5 10806.2 10839.7 572.5 615.0 651.4 661.4 665.2 5014.0 5072.0 5077.4 5119.2 5140.6 78697.0 89017.0 105963.0 109042.5 110809.4 72476.0 92175.5 92523.0 95895.0 98109.5 80701.0 83211.6 83807.6 83915.3 84590.0 9598.8 9897.9 9961.4 9989.5 10006.7 52747.0 54965.5 55338.7 55704.0 55895.7 47977.0 57134.4 64715.7 67379.3 67514.6 12781.4 12914.6 13125.6 13376.9 13774.8 14786.3 14799.8 15073.0 15260.9 15487.6 12795.1 12958.7 13697.8 14233.9 14418.4 13838.5 13902.2 14191.2 14398.6 14650.1 13385.0 13671.2 13814.2 13872.0 13992.3 9804.2 10147.2 10240.4 10480.1 10589.4 11288.3 11378.8 11396.1 11633.9 11637.8 09:40:06 INFO - PROCESS | 1640 | -------- Summary: end -------- 09:40:06 INFO - PROCESS | 1640 | 09:40:06 INFO - TEST-INFO | 1640: exit 0 09:40:06 INFO - TEST-OK | dromaeo_css | took 1116356ms 09:40:06 INFO - TEST-START | kraken 09:40:06 INFO - operating with platform_type : mac_ 09:40:06 INFO - Initialising browser for kraken test... 09:40:06 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmprXCdZF/profile http://localhost:49226/getInfo.html) 09:40:09 INFO - PROCESS | 1652 | 2017-06-06 09:40:09.408 plugin-container[1654:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 09:40:09 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:09 INFO - PROCESS | 1652 | 2017-06-06 09:40:09.409 plugin-container[1654:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424f, name = 'com.apple.CFPasteboardClient' 09:40:09 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:09 INFO - PROCESS | 1652 | 2017-06-06 09:40:09.410 plugin-container[1654:10169] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:40:09 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:40:09 INFO - PROCESS | 1652 | colorDepth:24 09:40:09 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:40:09 INFO - PROCESS | 1652 | __metrics 09:40:09 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 09:40:10 INFO - TEST-INFO | 1652: exit 0 09:40:10 INFO - Browser initialized. 09:40:10 INFO - Running cycle 1/1 for kraken test... 09:40:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmprXCdZF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:12 INFO - PROCESS | 1655 | 2017-06-06 09:40:12.568 plugin-container[1656:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 09:40:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:12 INFO - PROCESS | 1655 | 2017-06-06 09:40:12.568 plugin-container[1656:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 09:40:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:12 INFO - PROCESS | 1655 | 2017-06-06 09:40:12.568 plugin-container[1656:10296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:40:22 INFO - PROCESS | 1655 | RSS: Main: 202670080 09:40:22 INFO - PROCESS | 1655 | 09:40:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 09:40:26 INFO - PROCESS | 1655 | RSS: Main: 216203264 09:40:26 INFO - PROCESS | 1655 | 09:40:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 09:40:29 INFO - PROCESS | 1655 | RSS: Main: 224075776 09:40:29 INFO - PROCESS | 1655 | 09:40:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 09:40:33 INFO - PROCESS | 1655 | RSS: Main: 227663872 09:40:33 INFO - PROCESS | 1655 | 09:40:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 09:40:36 INFO - PROCESS | 1655 | RSS: Main: 228147200 09:40:36 INFO - PROCESS | 1655 | 09:40:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:40:38 INFO - PROCESS | 1655 | RSS: Main: 228184064 09:40:38 INFO - PROCESS | 1655 | 09:40:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 09:40:42 INFO - PROCESS | 1655 | RSS: Main: 232108032 09:40:42 INFO - PROCESS | 1655 | 09:40:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 09:40:46 INFO - PROCESS | 1655 | RSS: Main: 231534592 09:40:46 INFO - PROCESS | 1655 | 09:40:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 09:40:49 INFO - PROCESS | 1655 | RSS: Main: 233140224 09:40:49 INFO - PROCESS | 1655 | 09:40:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:40:52 INFO - PROCESS | 1655 | RSS: Main: 232640512 09:40:52 INFO - PROCESS | 1655 | 09:40:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:54 INFO - PROCESS | 1655 | RSS: Main: 232636416 09:40:54 INFO - PROCESS | 1655 | 09:40:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:57 INFO - PROCESS | 1655 | RSS: Main: 232656896 09:40:57 INFO - PROCESS | 1655 | 09:41:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:41:01 INFO - PROCESS | 1655 | RSS: Main: 233316352 09:41:01 INFO - PROCESS | 1655 | 09:41:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:41:04 INFO - PROCESS | 1655 | RSS: Main: 232841216 09:41:04 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 09:41:06 INFO - PROCESS | 1655 | RSS: Main: 235384832 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | __start_tp_report 09:41:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:41:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:41:06 INFO - PROCESS | 1655 | |i|pagename|runs| 09:41:06 INFO - PROCESS | 1655 | |0;ai-astar;106;118;118;122;119;119;121;120;167;174 09:41:06 INFO - PROCESS | 1655 | |1;audio-beat-detection;158;101;100;143;146;140;134;110;130;129 09:41:06 INFO - PROCESS | 1655 | |2;audio-dft;200;198;199;195;199;229;230;199;198;197 09:41:06 INFO - PROCESS | 1655 | |3;audio-fft;84;59;63;90;63;61;62;63;75;63 09:41:06 INFO - PROCESS | 1655 | |4;audio-oscillator;80;72;83;74;71;71;81;73;71;83 09:41:06 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;90;90;89;90;108;110;90;90;88 09:41:06 INFO - PROCESS | 1655 | |6;imaging-darkroom;122;107;98;98;96;97;98;96;96;98 09:41:06 INFO - PROCESS | 1655 | |7;imaging-desaturate;90;102;90;91;92;91;107;110;91;95 09:41:06 INFO - PROCESS | 1655 | |8;json-parse-financial;66;68;68;68;76;80;108;110;106;74 09:41:06 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;57;59;63;73;66;64;57;57;58;60 09:41:06 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;87;88;107;103;103;72;122;107;107;110 09:41:06 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;132;144;169;139;144;156;140;141;198;174 09:41:06 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;173;173;171;169;171;166;170;172;170;193 09:41:06 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;65;69;68;69;66;67;66;66;68;65 09:41:06 INFO - PROCESS | 1655 | __end_tp_report 09:41:06 INFO - PROCESS | 1655 | __start_cc_report 09:41:06 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1031 09:41:06 INFO - PROCESS | 1655 | __end_cc_report 09:41:06 INFO - PROCESS | 1655 | __startTimestamp1496767266929__endTimestamp 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:41:06 INFO - PROCESS | 1655 | Number of tests: 14 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:128.40 Median:120.50 stddev:22.68 (18.8%) stddev-sans-first:22.56 09:41:06 INFO - PROCESS | 1655 | Values: 106.0 118.0 118.0 122.0 119.0 119.0 121.0 120.0 167.0 174.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:137.00 stddev:19.60 (14.3%) stddev-sans-first:17.79 09:41:06 INFO - PROCESS | 1655 | Values: 158.0 101.0 100.0 143.0 146.0 140.0 134.0 110.0 130.0 129.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:204.40 Median:199.00 stddev:13.30 (6.7%) stddev-sans-first:14.01 09:41:06 INFO - PROCESS | 1655 | Values: 200.0 198.0 199.0 195.0 199.0 229.0 230.0 199.0 198.0 197.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:68.30 Median:63.00 stddev:10.82 (17.2%) stddev-sans-first:9.88 09:41:06 INFO - PROCESS | 1655 | Values: 84.0 59.0 63.0 90.0 63.0 61.0 62.0 63.0 75.0 63.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:5.20 (6.7%) stddev-sans-first:5.29 09:41:06 INFO - PROCESS | 1655 | Values: 80.0 72.0 83.0 74.0 71.0 71.0 81.0 73.0 71.0 83.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:90.00 stddev:8.21 (9.1%) stddev-sans-first:8.61 09:41:06 INFO - PROCESS | 1655 | Values: 90.0 90.0 90.0 89.0 90.0 108.0 110.0 90.0 90.0 88.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:97.50 stddev:8.18 (8.4%) stddev-sans-first:3.42 09:41:06 INFO - PROCESS | 1655 | Values: 122.0 107.0 98.0 98.0 96.0 97.0 98.0 96.0 96.0 98.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:91.00 stddev:7.58 (8.3%) stddev-sans-first:7.73 09:41:06 INFO - PROCESS | 1655 | Values: 90.0 102.0 90.0 91.0 92.0 91.0 107.0 110.0 91.0 95.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:82.40 Median:68.00 stddev:18.20 (26.8%) stddev-sans-first:18.32 09:41:06 INFO - PROCESS | 1655 | Values: 66.0 68.0 68.0 68.0 76.0 80.0 108.0 110.0 106.0 74.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:5.19 (8.4%) stddev-sans-first:5.25 09:41:06 INFO - PROCESS | 1655 | Values: 57.0 59.0 63.0 73.0 66.0 64.0 57.0 57.0 58.0 60.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:100.60 Median:116.00 stddev:14.31 (12.3%) stddev-sans-first:14.30 09:41:06 INFO - PROCESS | 1655 | Values: 87.0 88.0 107.0 103.0 103.0 72.0 122.0 107.0 107.0 110.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:150.00 stddev:20.65 (13.8%) stddev-sans-first:20.36 09:41:06 INFO - PROCESS | 1655 | Values: 132.0 144.0 169.0 139.0 144.0 156.0 140.0 141.0 198.0 174.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:171.50 stddev:7.39 (4.3%) stddev-sans-first:7.84 09:41:06 INFO - PROCESS | 1655 | Values: 173.0 173.0 171.0 169.0 171.0 166.0 170.0 172.0 170.0 193.0 09:41:06 INFO - PROCESS | 1655 | 09:41:06 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.52 (2.3%) stddev-sans-first:1.45 09:41:06 INFO - PROCESS | 1655 | Values: 65.0 69.0 68.0 69.0 66.0 67.0 66.0 66.0 68.0 65.0 09:41:06 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:41:06 INFO - PROCESS | 1655 | 09:41:07 INFO - TEST-INFO | 1655: exit 0 09:41:07 INFO - TEST-OK | kraken | took 61082ms 09:41:07 INFO - SUITE-END | took 1177s 09:41:07 INFO - Completed test suite (00:19:37) 09:41:07 INFO - javascript benchmark 09:41:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7722.485732443153, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2889.7977141600086, 3261.6575259597726, 3489.1102768793953, 3513.2622149348636, 3569.2861427714456, 4653.7440440775745, 5964.79123230687, 6060.424259695327, 6070.878582428349, 6097.5426842986735, 6060.545459090564, 6091.756329746816, 6119.0, 6138.693065346726, 6173.876522469548, 5405.216243644679, 5490.725463726818, 5503.367112782022, 5514.834554963358, 5521.199426083215, 4713.835015774449, 5440.319960004999, 5537.916931246034, 5556.805511807088, 5584.553235741131, 5309.203619457074, 5319.361676598814, 5525.861853453655, 5527.170924361358, 5589.161625756149, 5507.173923911405, 5521.723913804293, 5552.305961754781, 5567.8608034799045, 5606.0, 5381.219723140122, 5438.0, 5442.891142177174, 5474.91787623186, 5486.972565137189, 5777.104548794943, 6067.0, 6099.146119543268, 6164.013757798775, 6202.162708034448, 5993.61041532299, 6346.2067241594805, 6367.745290188387, 6374.745010199586, 6401.327860574619, 6519.771807986745, 6520.869582608369, 6532.183477065367, 6551.148350714424, 6619.569727967667, 5136.486351364871, 6235.594686345373, 6247.0, 6260.280067792217, 6262.185915830913, 6320.8419789504715, 6376.649284289316, 6391.872162556723, 6426.2931077581425, 6526.314736952647, 6347.39699728525, 6386.329435409215, 6389.52078594098, 6390.584612000205, 6410.967945160338, 6450.838729031718, 6462.4183823456115, 6476.222853257545, 6490.870182596322, 6492.5455218134275, 6254.437100660963, 6318.526110541728, 6344.365563443619, 6403.551751377358, 6432.388923052303, 6339.714712837934, 6342.334054924258, 6360.204974378203, 6432.614043157426, 6452.451541619015, 4897.632677549127, 4913.336699545587, 4954.20732682769, 4969.304297398295, 4979.90040199194, 3808.4287356896134, 4215.114825886598, 4452.844150454784, 4468.3074123510905, 4495.415595972503, 5769.048107062287, 6062.030075187949, 6086.665233412116, 6092.238470191226, 6097.451229389376, 6296.748130074838, 6336.651484168415, 6344.746210151635, 6369.777057803048, 6396.488280937514, 5934.673592952429, 5967.104934259809, 5978.760849566055, 5986.670733109634, 6012.038073908154, 4660.021395506914, 5321.760520776575, 5588.133839254922, 5609.102543593006, 5614.29821272341, 6227.719752611053, 6406.455451286506, 6495.9025614614875, 6516.609003459807, 6630.535862489773, 6495.480361571157, 6502.967485162544, 6513.5114866384265, 6515.185601799775, 6523.902141467787, 6669.966650166718, 6683.665816709048, 6706.597604143767, 6719.865602687821, 6725.125733654593, 6279.215098112736, 6327.810165695037, 6328.462080723006, 6352.809415717536, 6466.967665161644, 6470.6441145737435, 6474.158359413246, 6485.481161506974, 6492.318306577741, 6514.967425162844, 5601.887962240814, 6253.1245625613365, 6303.810885673437, 6355.3326900674765, 6361.840953976002, 6165.260168779596, 6236.501079913687, 6266.216722909637, 6277.623342599459, 6361.777337793155, 6216.409441103088, 6276.560640755103, 6321.715522801577, 6330.208723909512, 6348.143000695032, 6373.267074286517, 6373.74505019794, 6375.6174629522375, 6383.042543618309, 6398.840028999312, 6101.450869421596, 6120.386183820725, 6167.013277875519, 6238.968805155945, 6254.9687251563455], "value": 5895.937918717352, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.198641426502, 788.1645455816835, 834.1450013735922, 864.4553931023454, 967.0764419978921, 34916.0, 40046.99882502939, 41930.37104443428, 41968.790156049174, 42076.15847683048, 40573.79713101438, 40732.0, 40901.590984090224, 40908.77273681787, 40974.0, 8139.0, 11171.66485005447, 11394.430278486085, 11573.36346500942, 11602.651920442358, 9428.434293942351, 11134.49894754738, 11184.944075279613, 11221.158413119008, 11236.943815280913, 11186.608468703596, 11227.101831853432, 11236.438178091104, 11251.437428128602, 11253.156013298994, 11081.224314298002, 11157.330560166376, 11162.720931976684, 11176.832347514794, 11179.993800557908, 11001.339919604849, 11026.283291586022, 11050.557977680883, 11068.0, 11075.446227688582, 35401.822990885135, 35632.46551301733, 35656.93029209102, 35676.64323356759, 35751.642483575095, 3816.2939856126704, 3867.361885288924, 3912.5500567434824, 3913.6673382762215, 3958.366661334173, 26110.216693499224, 26183.607245891137, 26252.474950501073, 27689.446211075665, 27871.581926271123, 3107.5494053362136, 3153.5269709543522, 3196.8881089162, 3200.0559834848777, 3300.042987533612, 313826.0, 318226.0, 344238.0, 345274.27362863277, 349277.2536137329, 44379.77810110929, 44681.77659111684, 44822.32766508505, 44907.77546112249, 44938.0, 46281.0, 46684.06631867344, 46688.299675504895, 46697.766511167225, 46802.06395872063, 13857.51498697561, 13932.721345573033, 13961.232132232824, 14003.229822359664, 14034.719305613831, 12244.326562039174, 12289.262644241195, 12292.508299668092, 12307.015438765056, 12316.692082698004, 10776.515056822462, 10971.177161712743, 12054.758904821854, 12140.472906797666, 12199.085068619712, 4444.355568442557, 5401.513863752281, 5778.913316300259, 5874.118882167623, 5880.852978675482, 736.3189050128609, 741.6514238307986, 824.4929368438367, 868.9391742577959, 886.3618194899661, 23442.413939651306, 26437.074702385326, 26569.46861062768, 27380.863095684792, 27758.722412775613, 953.1945506047517, 958.520739630185, 991.8462638291077, 992.5831150916632, 995.4773743784571, 13738.313084345742, 14411.711765764627, 14472.927635361755, 14952.70094598102, 15372.077675339291, 498447.0155298473, 501071.0, 503346.0, 503380.0, 504287.0, 50927.49072509228, 51044.0, 51226.0, 51239.487605123475, 51498.48501514937, 1322.3057894605408, 1361.6731984323692, 1362.3937347880296, 1363.3047145955634, 1366.1120271823395, 27958.0, 28089.859550702116, 28582.42835143327, 28807.0, 29193.270168245963, 42728.0, 42965.57034429616, 43077.0, 43100.56899430966, 43127.56872431361, 445268.0, 448275.0, 450821.0, 451213.0, 451449.0, 29301.70698293075, 30509.54235686511, 30578.08265752031, 30723.539146912368, 32619.18452038718, 38861.80569097136, 39271.0, 40054.59945400509, 40192.0, 40327.0, 27621.44757104887, 28227.43545129127, 28614.998475054104, 29280.121596352143, 29466.0, 28637.570436443053, 28754.281142971595, 28825.279368015967, 29058.27354316159, 29552.852235738683, 32981.01056968295, 33762.49356259707, 33969.660303397635, 34354.48468272928, 34546.309073818884, 23377.46366713106, 25300.87349563314, 28015.85992070108, 29426.705732942395, 29529.85235073811, 22632.773672263065, 22659.660105098108, 23656.763432365457, 23744.0, 23815.8092095398], "value": 18257.89882539025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7632953074798, 407.1490584678023, 408.8528129873247, 408.96523795477384, 448.3588468490058, 20807.0, 20816.375508734724, 22377.888110559466, 22403.21588744394, 22414.88792556039, 22511.09955601778, 22543.210987615435, 22718.432039198986, 22915.0, 23014.07943682255, 6868.381845633904, 6990.895136572955, 7018.4736144789085, 7021.122359705037, 7121.4302855771675, 6634.270230274666, 7168.283171682816, 7178.138623365204, 7185.461090418214, 7201.279872012783, 6873.828154296132, 6881.208661003999, 6983.196932352794, 7151.821204469878, 7156.0, 6901.827454313632, 6929.272426395231, 6980.755673551426, 6985.6507174641065, 7018.0, 6483.189601299838, 6889.655517224119, 6900.413464855494, 6901.999210114505, 6915.619640919747, 27037.594436083473, 27585.724142758718, 27663.58504622432, 27664.308392290153, 27957.86021069902, 2836.262571731344, 2969.5545668149734, 3026.9091927242216, 3439.3121375724954, 3443.517907492953, 16216.594585135348, 16554.503364899072, 16804.90768100082, 16850.410235641695, 17366.131693415406, 1833.7982821889582, 2116.619008578455, 2174.8695078295195, 2193.8683678979314, 2210.889455527233, 158904.20547897185, 162653.0, 163294.0, 164707.0, 168416.15791921085, 21650.133994640037, 21854.45363865916, 22106.0, 22260.0, 22310.55378892413, 27286.727132728418, 32672.346553068804, 32827.34345313128, 33207.3358532828, 33231.66768332334, 10660.040596346365, 10793.40636265013, 10905.945470272598, 10996.14810077879, 11062.391568463812, 6799.796006119825, 7398.889016664754, 8125.9842519685035, 9049.954750226338, 9229.769255768526, 8249.257566819015, 9961.800763984824, 9997.400155990665, 10082.991700829858, 10104.646337378303, 3652.926941461156, 4368.825246990148, 4371.868843934687, 4379.386885835986, 4460.821567137343, 912.8676341930383, 1032.2155162076726, 1036.875574931013, 1068.2095249515303, 1094.261373572835, 20986.950652467316, 22241.11035558592, 22264.220752273915, 22266.220682276366, 22271.888640556692, 1067.9466026698633, 1068.899033994971, 1082.7726177502814, 1092.8415379769888, 1109.683740134074, 10888.346699198073, 11566.392632031126, 11622.186446948801, 11624.593139240085, 11656.708582285512, 742377.0, 744577.0, 747366.0, 752619.0, 762829.0, 121417.0, 146028.0, 151932.0, 154294.0, 156295.0, 1219.1344145656506, 1222.6270987348944, 1262.31834809202, 1267.6133779197248, 1276.6595075171033, 15192.620184495476, 18553.25786968506, 19380.709289360388, 20946.371608851758, 21118.155273788878, 60131.0, 62245.0, 63214.367856320845, 63414.365856340846, 63792.0, 88008.39937004622, 90041.0, 90587.0, 91609.0, 92751.0, 9128.361014729171, 10110.595576176871, 11494.195406321478, 11738.471768770254, 11893.583724569944, 11765.647030589096, 11774.175807693378, 11993.82009269879, 12237.449314780679, 13304.0, 9528.61885524599, 11353.069978103907, 11647.883521165018, 11746.882531174579, 11817.645470635895, 10585.4707264638, 10657.680269591925, 10702.571897124028, 10799.946000269949, 10978.176636752436, 11626.849032928934, 11677.649670509898, 11882.227655202385, 11932.821007685065, 11996.76006479883, 11521.423928803693, 11572.074234061087, 11631.47658355393, 11881.643550693492, 11938.22401543897, 10773.01002416897, 11300.0, 11357.602483913353, 11515.654530363767, 11693.006094482063], "value": 11847.47876482117, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.669319442917, 1602.8940031378347, 1610.7181243282425, 1613.0483015021134, 1617.401561422274, 10792.838107428384, 11204.551817927273, 11288.830667539984, 11421.885781142197, 11523.827142592867, 11241.494132764024, 11381.0, 11409.714757131076, 11417.086633069355, 11440.084793216543, 2346.0498498108295, 2408.494216214588, 2412.264259400882, 2414.625733011377, 2415.710114786228, 2333.5449587330477, 2412.5054363855365, 2416.66166736657, 2423.466837295792, 2435.7198922123916, 2352.870592117433, 2404.1224952892157, 2415.408224984881, 2427.8786060696984, 2427.987860060706, 2330.196082351586, 2389.426537630965, 2396.0, 2396.916107936231, 2407.1935901473025, 2364.5861974154627, 2373.738888722239, 2383.653235921716, 2390.6772585701056, 2401.5076909233567, 9496.382735122264, 11039.668809935714, 11271.211015228937, 11292.266002709797, 11314.717132071682, 5746.42535746422, 5919.940800592025, 5933.050711886078, 5936.851578710523, 5951.821445356646, 3524.312759011994, 3606.2787442511603, 3655.981720091409, 3657.4330978698345, 3663.8351274192723, 1117.9552817887275, 1199.3703305764457, 1221.4381384563112, 1281.385454327554, 1287.7231395249974, 11146.944265278622, 12570.68573285666, 14008.579742607739, 14456.78314825268, 15454.768178477332, 4175.874723758292, 4545.636349092131, 4607.101615185074, 4741.976290118596, 4749.810007599726, 10382.584696612203, 11557.959783619515, 11743.76512469763, 11901.702457438634, 11924.821127683092, 5711.3146422428745, 5743.511801496919, 5788.739506722208, 5790.986577348947, 5805.82582522525, 5169.71566563835, 5182.196759502283, 5256.553192978563, 5299.68201907879, 5327.680339179662, 3274.8690052398115, 3834.7699138051807, 3913.7651740895067, 3914.3149948758855, 3968.0873399118364, 1002.8997100289913, 1014.1633152649094, 1018.8981101889751, 1024.4519182237616, 1027.1423361493087, 807.7738233294687, 841.760098371961, 899.6401439424152, 906.3429013964954, 933.5892207428709, 4911.656184067152, 5846.853828654318, 6433.839154021187, 6460.160179176771, 6560.737570497126, 922.4649703172144, 1003.8042581696499, 1014.4420568687193, 1058.751193469534, 1083.8374243863325, 3922.843086276517, 4106.425100485934, 4212.599803018708, 4238.554951730086, 4239.533651298355, 13115.147515411467, 13612.31938403076, 13636.181829090287, 13662.726745465035, 13671.906247500176, 13399.263040532864, 13444.663883402993, 13454.32728363578, 13788.103773254707, 13813.447462101403, 8823.0, 10275.537600807982, 10316.535755890853, 10423.061924426838, 10475.16198704117, 6188.752449901954, 6599.142111525471, 6607.0419789131265, 6817.5909445433435, 6841.93158068413, 11648.146204179691, 11785.941070294593, 12096.334701591497, 12174.695632609255, 12187.878121218675, 11965.880341196476, 12070.637880863587, 12120.0, 12177.939110304746, 12259.938700306442, 9161.725148245563, 9283.0, 9427.57575909072, 9446.669366572138, 9466.290028247771, 10321.690349289533, 10350.896491034993, 10404.271700980858, 10433.0, 10458.0, 8676.436031657922, 8882.689105881522, 8904.0, 8914.643414263617, 9096.454212747256, 8667.739967800977, 8669.913300867163, 8722.302215822845, 8734.56327183651, 8766.868496972424, 7809.297163255334, 7872.921270787218, 7937.047554293291, 7956.283934445928, 7963.960180199062, 5761.308642962871, 5793.246877906014, 5811.4188581143235, 5947.672877991752, 6029.125776762433, 5773.711314434177, 5880.911786323123, 6024.186734790747, 6064.6960903404215, 6129.172561704113], "value": 5108.864152718469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.0, 329.2854505722582, 386.5361566120656, 386.79113278829453, 389.8460108257239, 4956.67781594197, 5233.502817232362, 5278.258174802316, 5337.386200586933, 5350.705711185883, 4256.936145957813, 4279.33670281107, 4796.448408433032, 5094.184930411135, 5251.133562962106, 3825.8087095645246, 4129.525104612963, 4178.832846686144, 4342.826286948534, 4353.869383918487, 3628.1859070464766, 4290.76400797956, 4305.67707421943, 4325.956740432587, 4366.345048242773, 3793.5447746270347, 3827.8086095695244, 3828.8277027533827, 3965.583613720561, 4327.394164816928, 3537.451694987268, 3800.90497737556, 3987.4018897165365, 4012.9398059029136, 4291.613754762071, 3530.9117272068147, 3683.244934788362, 3918.8628398006217, 3951.4467974483596, 3983.920321593581, 4660.0, 4884.900897298115, 4999.275105109775, 5038.531119751332, 5076.64463487556, 4314.913701725979, 4735.5974742146955, 4985.675931064469, 5028.673136246132, 5040.470750571174, 4703.247480403119, 5019.949800502021, 5123.359580052494, 5155.432902380735, 5633.098704207348, 3497.720182385403, 3843.731568582389, 4463.977680111611, 4471.597556219924, 4499.460064792246, 5186.870328241786, 5877.294724633071, 5924.45964049346, 5940.594059405904, 5954.5534084943365, 4323.0, 4359.324304732771, 4403.732077979843, 4570.565796249351, 4593.678442509059, 4600.401947746838, 4644.814207431665, 4647.790849411785, 4650.3956135134, 4675.6727029108315, 4693.0, 4928.507149285115, 4963.205887058053, 5051.055343692705, 5065.772040258197, 4808.0, 5253.684778913277, 5456.427075157131, 5530.695811730394, 5574.888502229933, 1316.960491185266, 1377.5591810620506, 1583.1609247099057, 1587.8253392126858, 1592.554084856242, 1008.3042700536606, 1098.7912296663608, 1159.0032571988083, 1192.3442106841376, 1197.9401029948467, 4861.805527778849, 4923.852284431473, 4968.714391379934, 4986.800527978913, 5008.84973450797, 5173.379194496684, 5255.054090263713, 5698.6580805151825, 5744.741486633112, 5793.768249270066, 4573.359729637853, 4753.407608451138, 4856.878578035577, 4982.950170498321, 5015.548600625962, 2462.8891699873548, 2743.903963361273, 2800.523910935146, 2804.8737806798745, 3013.668496465387, 4731.266653668687, 5019.774110165052, 5074.670146440543, 5502.80740174092, 5789.002794036051, 4781.760911954389, 5410.670489567772, 5481.725913704299, 5638.0, 5907.261592300963, 4892.336605554128, 5374.253367655528, 5951.4048595141, 5983.850403739942, 5990.700464976734, 4435.689501734848, 5590.916136258041, 5687.544996400361, 5754.9136762947755, 5759.596828222152, 4856.587190088882, 5403.0, 5488.4785945335125, 5686.402927692533, 5708.257926469532, 5072.452901864966, 5534.335879694462, 5566.0, 5593.497234726085, 5793.420657934173, 4038.91922161561, 4091.1408604193452, 4532.388127602731, 4577.771111444481, 4594.517575654508, 5297.947020529746, 5327.9610475956815, 5517.393086760533, 5843.2403787507355, 5890.823275301748, 4613.423322084739, 4625.872098834439, 4885.676176583678, 5014.899702006012, 5616.522595579258, 4398.30665193909, 5423.810166644306, 5611.971940140274, 5664.518515926027, 5674.7370262100285, 5071.721055341992, 5104.821331253388, 5131.511861560106, 5485.790812374646, 5515.145152501369, 4867.415910090812, 5555.833275012293, 5642.576806739593, 5650.576206784425, 5674.233978412862, 4440.7335559866515, 4813.31781187385, 4896.804127834989, 5228.9671990246325, 5489.204065410413], "value": 4173.815587583169, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.58508857407, 725.4559080689482, 737.793417843004, 792.2988155482396, 855.9443636163649, 15405.0, 16318.020918744869, 16885.49343519693, 17095.0, 17294.2217600208, 15011.399544018253, 15040.774388384183, 15763.684726305495, 15907.840921590781, 16091.678166436694, 960.3661583354988, 962.311946957925, 962.3840741925138, 962.4754508792707, 964.0793042644258, 936.4615346175942, 939.8261321655482, 956.6077908057698, 986.8865080515725, 1001.5743309093606, 858.7981824271292, 1021.6781713760141, 1023.6366090038041, 1024.748936510556, 1024.87701475823, 884.5135175653365, 1019.3679918450571, 1019.6991887393162, 1020.4030642074321, 1022.6522982186056, 755.9055118110236, 759.0625577411892, 760.1258552664425, 760.7033257029763, 760.9809754756176, 15052.0, 15342.769858452131, 16024.718022558167, 17068.658626827517, 17091.743623845654, 14427.0, 14485.637859053502, 14523.346449409697, 14525.201113938729, 14700.779488307684, 11654.417279136009, 11784.351860647661, 11805.0, 11948.880511194864, 12307.0, 3693.4275187346007, 3786.659200671953, 3803.904902377435, 3805.8858234253016, 3950.8419663213435, 96925.0, 114350.42824785906, 118487.0, 121277.0, 122451.0, 10963.177761667906, 11067.944660276808, 11453.370064646524, 11479.13906457019, 11536.884631153582, 23242.83785810703, 23471.7652823473, 24043.879780600986, 24147.7585224149, 24152.87923560371, 7645.694172233048, 8010.279074883289, 8077.151899050633, 8107.635156417975, 8178.018637763506, 4333.6316413104605, 4365.388845561624, 4450.376947227391, 4525.298578720303, 4533.977330113328, 3517.944616615005, 3613.2050948791225, 3613.9277214456088, 3618.620044895301, 3619.475176099451, 626.5488848029412, 629.7921685843752, 631.4064779107624, 646.1277275677797, 655.8917778566579, 635.9141515895387, 663.2538394306405, 685.5954986557955, 687.2680595166132, 691.339770519153, 10417.427041512638, 10427.980668626346, 10433.530491127765, 10806.243562950674, 10839.729006774736, 572.4647454629934, 614.9617626267327, 651.364919203782, 661.355178700763, 665.1585744033802, 5014.022265658235, 5072.036313100499, 5077.365329333833, 5119.180931051014, 5140.563052140536, 78697.0, 89017.0, 105963.0, 109042.45478772714, 110809.44595276972, 72476.0, 92175.5391223053, 92523.0, 95895.0, 98109.50945245229, 80701.0, 83211.5839420799, 83807.5809620948, 83915.32169356578, 84590.0, 9598.808023839345, 9897.901020989699, 9961.40231586107, 9989.500524973577, 10006.749831254278, 52747.0, 54965.450345496036, 55338.72330638482, 55704.0, 55895.72052139713, 47977.04045918992, 57134.42865571291, 64715.67642161759, 67379.31551711247, 67514.6497070046, 12781.424835882222, 12914.612561622791, 13125.60623181306, 13376.929845612132, 13774.793378099537, 14786.260686965823, 14799.778003329744, 15073.0, 15260.923695381452, 15487.612809679398, 12795.104342695922, 12958.740825183631, 13697.794533081813, 14233.928830355782, 14418.351174196976, 13838.515651952133, 13902.23537705436, 14191.219482928538, 14398.568042958728, 14650.120992740469, 13385.0, 13671.17972921628, 13814.240216788174, 13871.959603030014, 13992.300384980914, 9804.215662746821, 10147.188224942134, 10240.385576865412, 10480.074679392574, 10589.364638121737, 11288.322700637988, 11378.829317560077, 11396.145289103517, 11633.941830290794, 11637.825432618349], "value": 7798.55291012126, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 118.0, 122.0, 119.0, 119.0, 121.0, 120.0, 167.0, 174.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 101.0, 100.0, 143.0, 146.0, 140.0, 134.0, 110.0, 130.0, 129.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 199.0, 195.0, 199.0, 229.0, 230.0, 199.0, 198.0, 197.0], "value": 204.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 63.0, 90.0, 63.0, 61.0, 62.0, 63.0, 75.0, 63.0], "value": 68.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 83.0, 74.0, 71.0, 71.0, 81.0, 73.0, 71.0, 83.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 89.0, 90.0, 108.0, 110.0, 90.0, 90.0, 88.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 107.0, 98.0, 98.0, 96.0, 97.0, 98.0, 96.0, 96.0, 98.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 90.0, 91.0, 92.0, 91.0, 107.0, 110.0, 91.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 68.0, 76.0, 80.0, 108.0, 110.0, 106.0, 74.0], "value": 82.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 63.0, 73.0, 66.0, 64.0, 57.0, 57.0, 58.0, 60.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 107.0, 103.0, 103.0, 72.0, 122.0, 107.0, 107.0, 110.0], "value": 100.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 144.0, 169.0, 139.0, 144.0, 156.0, 140.0, 141.0, 198.0, 174.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 171.0, 169.0, 171.0, 166.0, 170.0, 172.0, 170.0, 193.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 68.0, 69.0, 66.0, 67.0, 66.0, 66.0, 68.0, 65.0], "value": 66.9, "unit": "score"}]}]} 09:41:07 INFO - Return code: 0 09:41:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:41:07 ERROR - # TBPL SUCCESS # 09:41:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:07 INFO - 2017-06-06 09:41:07.776 screenresolution[1660:10675] starting screenresolution argv=screenresolution get 09:41:07 INFO - 2017-06-06 09:41:07.779 screenresolution[1660:10675] Display 0: 1600x1200x32@60 09:41:07 INFO - 2017-06-06 09:41:07.785 screenresolution[1661:10678] starting screenresolution argv=screenresolution list 09:41:07 INFO - Available Modes on Display 0 09:41:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:08 INFO - Intel Iris: 09:41:08 INFO - Chipset Model: Intel Iris 09:41:08 INFO - Type: GPU 09:41:08 INFO - Bus: Built-In 09:41:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:08 INFO - Vendor: Intel (0x8086) 09:41:08 INFO - Device ID: 0x0a2e 09:41:08 INFO - Revision ID: 0x0009 09:41:08 INFO - Displays: 09:41:08 INFO - Display: 09:41:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:08 INFO - Display Serial Number: 1600x1200 60 09:41:08 INFO - Main Display: Yes 09:41:08 INFO - Mirror: Off 09:41:08 INFO - Online: Yes 09:41:08 INFO - Rotation: Supported 09:41:08 INFO - Return code: 0 09:41:08 INFO - Running post-action listener: _resource_record_post_action 09:41:08 INFO - [mozharness: 2017-06-06 16:41:08.040814Z] Finished run-tests step (success) 09:41:08 INFO - Running post-run listener: _resource_record_post_run 09:41:08 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39344128; Write bytes: 511871488; Read time: 383; Write time: 1786 09:41:08 INFO - TinderboxPrint: CPU usage
54.3% 09:41:08 INFO - TinderboxPrint: I/O read bytes / time
39,344,128 / 383 09:41:08 INFO - TinderboxPrint: I/O write bytes / time
511,871,488 / 1,786 09:41:08 INFO - TinderboxPrint: CPU idle
2,191.5 (45.7%) 09:41:08 INFO - TinderboxPrint: CPU system
959.2 (20.0%) 09:41:08 INFO - TinderboxPrint: CPU user
1,646.9 (34.3%) 09:41:08 INFO - TinderboxPrint: Swap in / out
248,332,288 / 0 09:41:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 209848832; Write bytes: 212580352; Read time: 17097; Write time: 469 09:41:08 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35706880; Write bytes: 297873920; Read time: 353; Write time: 1311 09:41:08 INFO - Running post-run listener: _upload_blobber_files 09:41:08 INFO - Blob upload gear active. 09:41:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:41:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:41:08 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:41:08 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:41:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:41:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:41:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:41:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:10 INFO - (blobuploader) - INFO - Done attempting. 09:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:11 INFO - (blobuploader) - INFO - Done attempting. 09:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:12 INFO - (blobuploader) - INFO - Done attempting. 09:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:14 INFO - (blobuploader) - INFO - Done attempting. 09:41:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:41:14 INFO - Return code: 0 09:41:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:41:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:41:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e058029696bb9ad967d289031399c1018dac95d269da821cf9d3b2d1802d52deb80aacb75c358d805f91bd5527d3fa502be4d65139d2dbcda6c5ff17a531cc28", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e05b34e7180167d777b29bc3e6b7e31a93ad77bfa1eab6184ae46da3da3f3af7db29b14c29a03b3b6b29009ccc43d0a0a7a523150bed895ed8c7141f47c70715", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b720ce38c1845efde3b8870cf191bf5b0011dc83df9561e74ceabd751ddb153cc82b3b6ef451dba22aafd2c921dae7f5e19ea3105be4973ed1b9997d6da8c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc21af3200be84072f91b69bcbfb43a60383e541980b5ece8e6653196d987f76409496eaaa4fc00ab80c243a683b0f0d735c8445981cc169f8fddc7012a87129"} 09:41:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:41:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:41:14 INFO - Contents: 09:41:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e058029696bb9ad967d289031399c1018dac95d269da821cf9d3b2d1802d52deb80aacb75c358d805f91bd5527d3fa502be4d65139d2dbcda6c5ff17a531cc28", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e05b34e7180167d777b29bc3e6b7e31a93ad77bfa1eab6184ae46da3da3f3af7db29b14c29a03b3b6b29009ccc43d0a0a7a523150bed895ed8c7141f47c70715", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b720ce38c1845efde3b8870cf191bf5b0011dc83df9561e74ceabd751ddb153cc82b3b6ef451dba22aafd2c921dae7f5e19ea3105be4973ed1b9997d6da8c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc21af3200be84072f91b69bcbfb43a60383e541980b5ece8e6653196d987f76409496eaaa4fc00ab80c243a683b0f0d735c8445981cc169f8fddc7012a87129"} 09:41:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:41:14 INFO - Copying logs to upload dir... 09:41:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:41:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.963446 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-06 09:41:14.625521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 09:41:14.629458) ========= 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.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-Beta/sha512/e058029696bb9ad967d289031399c1018dac95d269da821cf9d3b2d1802d52deb80aacb75c358d805f91bd5527d3fa502be4d65139d2dbcda6c5ff17a531cc28", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e05b34e7180167d777b29bc3e6b7e31a93ad77bfa1eab6184ae46da3da3f3af7db29b14c29a03b3b6b29009ccc43d0a0a7a523150bed895ed8c7141f47c70715", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b720ce38c1845efde3b8870cf191bf5b0011dc83df9561e74ceabd751ddb153cc82b3b6ef451dba22aafd2c921dae7f5e19ea3105be4973ed1b9997d6da8c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc21af3200be84072f91b69bcbfb43a60383e541980b5ece8e6653196d987f76409496eaaa4fc00ab80c243a683b0f0d735c8445981cc169f8fddc7012a87129"} build_url:https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/Dg1BZSsvQW2LgvqdjVZQFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e058029696bb9ad967d289031399c1018dac95d269da821cf9d3b2d1802d52deb80aacb75c358d805f91bd5527d3fa502be4d65139d2dbcda6c5ff17a531cc28", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e05b34e7180167d777b29bc3e6b7e31a93ad77bfa1eab6184ae46da3da3f3af7db29b14c29a03b3b6b29009ccc43d0a0a7a523150bed895ed8c7141f47c70715", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6b720ce38c1845efde3b8870cf191bf5b0011dc83df9561e74ceabd751ddb153cc82b3b6ef451dba22aafd2c921dae7f5e19ea3105be4973ed1b9997d6da8c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc21af3200be84072f91b69bcbfb43a60383e541980b5ece8e6653196d987f76409496eaaa4fc00ab80c243a683b0f0d735c8445981cc169f8fddc7012a87129"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 09:41:14.663136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:41:14.663486) ========= 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.XmC99hma10/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.X9tQau1Whs/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.005222 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:41:14.717743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 09:41:14.718065) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 09:41:14.718388) ========= ========= Total master_lag: 0.19 =========